builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0359 starttime: 1490665395.63 results: success (0) buildid: 20170327162522 builduid: 6aabb3ae2efb458f9602be7074503d74 revision: f7f7ff518802ccc22f984cad4d2bbde47666d88c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.630952) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.631440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.631718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.657948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.658282) ========= 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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 18:43:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.66M=0.001s 2017-03-27 18:43:15 (9.66 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164242 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.846466) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.846780) ========= 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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.032540 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.901746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:15.902109) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev f7f7ff518802ccc22f984cad4d2bbde47666d88c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev f7f7ff518802ccc22f984cad4d2bbde47666d88c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 18:43:16,008 truncating revision to first 12 chars 2017-03-27 18:43:16,008 Setting DEBUG logging. 2017-03-27 18:43:16,008 attempt 1/10 2017-03-27 18:43:16,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/f7f7ff518802?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 18:43:16,664 unpacking tar archive at: pine-f7f7ff518802/testing/mozharness/ program finished with exit code 0 elapsedTime=0.939197 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:16.865045) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:16.865378) ========= script_repo_revision: f7f7ff518802ccc22f984cad4d2bbde47666d88c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:16.865756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:16.866089) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:43:16.925482) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 18:43:16.925824) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:43:17 INFO - MultiFileLogger online at 20170327 18:43:17 in /builds/slave/test 18:43:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:43:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:43:17 INFO - {'append_to_log': False, 18:43:17 INFO - 'base_work_dir': '/builds/slave/test', 18:43:17 INFO - 'blob_upload_branch': 'Pine', 18:43:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:43:17 INFO - 'branch': 'Pine', 18:43:17 INFO - 'buildbot_json_path': 'buildprops.json', 18:43:17 INFO - 'config_files': ('talos/mac_config.py',), 18:43:17 INFO - 'default_actions': ('clobber', 18:43:17 INFO - 'read-buildbot-config', 18:43:17 INFO - 'download-and-extract', 18:43:17 INFO - 'populate-webroot', 18:43:17 INFO - 'create-virtualenv', 18:43:17 INFO - 'install', 18:43:17 INFO - 'run-tests'), 18:43:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:43:17 INFO - 'download_minidump_stackwalk': True, 18:43:17 INFO - 'download_symbols': 'ondemand', 18:43:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:43:17 INFO - 'tooltool.py': '/tools/tooltool.py', 18:43:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:43:17 INFO - '/tools/misc-python/virtualenv.py')}, 18:43:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:43:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:43:17 INFO - 'gecko_profile': False, 18:43:17 INFO - 'gecko_profile_interval': 0, 18:43:17 INFO - 'installer_path': 'installer.exe', 18:43:17 INFO - 'log_level': 'info', 18:43:17 INFO - 'log_name': 'talos', 18:43:17 INFO - 'log_to_console': True, 18:43:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:43:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:43:17 INFO - 'opt_config_files': (), 18:43:17 INFO - 'pip_index': False, 18:43:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:43:17 INFO - 'cmd': ('bash', 18:43:17 INFO - '-c', 18:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:43:17 INFO - 'enabled': True, 18:43:17 INFO - 'halt_on_failure': False, 18:43:17 INFO - 'name': 'check_screen_resolution'},), 18:43:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:43:17 INFO - 'cmd': ('bash', 18:43:17 INFO - '-c', 18:43:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:43:17 INFO - 'enabled': True, 18:43:17 INFO - 'halt_on_failure': False, 18:43:17 INFO - 'name': 'check_screen_resolution'},), 18:43:17 INFO - 'run_cmd_checks_enabled': True, 18:43:17 INFO - 'suite': 'dromaeojs-e10s', 18:43:17 INFO - 'system_bits': '32', 18:43:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:43:17 INFO - 'title': 't-yosemite-r7-0359', 18:43:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:43:17 INFO - 'use_talos_json': True, 18:43:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:43:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:43:17 INFO - 'work_dir': 'build'} 18:43:17 INFO - [mozharness: 2017-03-28 01:43:17.140120Z] Running clobber step. 18:43:17 INFO - Running pre-action listener: _resource_record_pre_action 18:43:17 INFO - Running main action method: clobber 18:43:17 INFO - rmtree: /builds/slave/test/build 18:43:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:43:19 INFO - Running post-action listener: _resource_record_post_action 18:43:19 INFO - [mozharness: 2017-03-28 01:43:19.570858Z] Finished clobber step (success) 18:43:19 INFO - [mozharness: 2017-03-28 01:43:19.570989Z] Running read-buildbot-config step. 18:43:19 INFO - Running pre-action listener: _resource_record_pre_action 18:43:19 INFO - Running main action method: read_buildbot_config 18:43:19 INFO - Using buildbot properties: 18:43:19 INFO - { 18:43:19 INFO - "project": "", 18:43:19 INFO - "product": "firefox", 18:43:19 INFO - "script_repo_revision": "default", 18:43:19 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 18:43:19 INFO - "repository": "", 18:43:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 18:43:19 INFO - "buildid": "20170327162522", 18:43:19 INFO - "pgo_build": "False", 18:43:19 INFO - "basedir": "/builds/slave/test", 18:43:19 INFO - "buildnumber": 8, 18:43:19 INFO - "slavename": "t-yosemite-r7-0359", 18:43:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:43:19 INFO - "platform": "yosemite_r7", 18:43:19 INFO - "scheduler": "tests-pine-macosx64-talos", 18:43:19 INFO - "branch": "pine", 18:43:19 INFO - "repo_path": "projects/pine", 18:43:19 INFO - "revision": "f7f7ff518802ccc22f984cad4d2bbde47666d88c", 18:43:19 INFO - "stage_platform": "macosx64", 18:43:19 INFO - "builduid": "6aabb3ae2efb458f9602be7074503d74", 18:43:19 INFO - "slavebuilddir": "test" 18:43:19 INFO - } 18:43:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:43:19 INFO - Running post-action listener: _resource_record_post_action 18:43:19 INFO - [mozharness: 2017-03-28 01:43:19.574441Z] Finished read-buildbot-config step (success) 18:43:19 INFO - [mozharness: 2017-03-28 01:43:19.574585Z] Running download-and-extract step. 18:43:19 INFO - Running pre-action listener: _resource_record_pre_action 18:43:19 INFO - Running main action method: download_and_extract 18:43:19 INFO - mkdir: /builds/slave/test/build/tests 18:43: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')]} 18:43:19 INFO - trying https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:43:19 INFO - Downloading https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:43:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:43:20 INFO - Downloaded 1325 bytes. 18:43:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:43:20 INFO - Using the following test package requirements: 18:43:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:43:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:43:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:43:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:43:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'jsshell-mac.zip'], 18:43:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:43:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:43:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:43:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:43:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:43:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:43:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:43:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:43:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:43:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:43:20 INFO - Fetch https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:43:22 INFO - Expected file size: 52416765 18:43:22 INFO - Obtained file size: 52416765 18:43:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:43:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:43:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:43:38 INFO - Fetch https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:43:40 INFO - Expected file size: 52416765 18:43:40 INFO - Obtained file size: 52416765 18:43:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:43:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:43:55 INFO - Fetch https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:43:57 INFO - Expected file size: 14404171 18:43:57 INFO - Obtained file size: 14404171 18:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:43:57 INFO - trying https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:43:59 INFO - Downloaded 75640819 bytes. 18:43:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:43:59 INFO - mkdir: /builds/slave/test/properties 18:43:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:43:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:43:59 INFO - Contents: 18:43:59 INFO - build_url:https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:44:00 INFO - Running post-action listener: _resource_record_post_action 18:44:00 INFO - Running post-action listener: set_extra_try_arguments 18:44:00 INFO - [mozharness: 2017-03-28 01:44:00.271586Z] Finished download-and-extract step (success) 18:44:00 INFO - [mozharness: 2017-03-28 01:44:00.271691Z] Running populate-webroot step. 18:44:00 INFO - Running pre-action listener: _resource_record_pre_action 18:44:00 INFO - Running main action method: populate_webroot 18:44:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:44:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:44:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:44:00 INFO - '--tppagecycles', 18:44:00 INFO - '7'], 18:44:00 INFO - 'tests': ['tsvgm']}}, 18:44:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:44:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:44:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:44:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:44:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['damp', 'tps']}, 18:44:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'tests': ['damp', 'tps']}, 18:44:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['dromaeo_dom']}, 18:44:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:44:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['basic_compositor_video']}, 18:44:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:44:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['a11yr', 18:44:00 INFO - 'ts_paint', 18:44:00 INFO - 'tpaint', 18:44:00 INFO - 'sessionrestore', 18:44:00 INFO - 'sessionrestore_no_auto_restore', 18:44:00 INFO - 'tabpaint']}, 18:44:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['tsvgx', 18:44:00 INFO - 'tsvgr_opacity', 18:44:00 INFO - 'tart', 18:44:00 INFO - 'tscrollx', 18:44:00 INFO - 'cart', 18:44:00 INFO - 'tsvg_static']}, 18:44:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:44:00 INFO - 'tsvgr_opacity', 18:44:00 INFO - 'tart', 18:44:00 INFO - 'tscrollx', 18:44:00 INFO - 'cart', 18:44:00 INFO - 'tsvg_static']}, 18:44:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'talos_options': ['--disable-e10s'], 18:44:00 INFO - 'tests': ['tp5o']}, 18:44:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:44:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'talos_options': ['--disable-e10s', 18:44:00 INFO - '--xperf_path', 18:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:00 INFO - 'tests': ['tp5n']}, 18:44:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:44:00 INFO - 'talos_options': ['--xperf_path', 18:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:00 INFO - 'tests': ['tp5n']}}, 18:44:00 INFO - 'talos.zip': {'path': '', 18:44:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:44:00 INFO - Running post-action listener: _resource_record_post_action 18:44:00 INFO - [mozharness: 2017-03-28 01:44:00.289987Z] Finished populate-webroot step (success) 18:44:00 INFO - [mozharness: 2017-03-28 01:44:00.290125Z] Running create-virtualenv step. 18:44:00 INFO - Running pre-action listener: _resource_record_pre_action 18:44:00 INFO - Running main action method: create_virtualenv 18:44:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:44:00 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 18:44:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:44:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:44:00 INFO - Using real prefix '/tools/python27' 18:44:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:44:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:44:04 INFO - Installing pip.................done. 18:44:04 INFO - Return code: 0 18:44:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:44:04 INFO - Reading from file tmpfile_stdout 18:44:04 INFO - Output received: 18:44:04 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) 18:44:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:44: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')]} 18:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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')]} 18:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:05 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:05 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 18:44:05 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 18:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:05 INFO - 'HOME': '/Users/cltbld', 18:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:05 INFO - 'LOGNAME': 'cltbld', 18:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:05 INFO - 'MOZ_NO_REMOTE': '1', 18:44:05 INFO - 'NO_EM_RESTART': '1', 18:44:05 INFO - 'PAGER': '/bin/cat', 18:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:05 INFO - 'PWD': '/builds/slave/test', 18:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:05 INFO - 'SHELL': '/bin/bash', 18:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:05 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:05 INFO - 'USER': 'cltbld', 18:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:05 INFO - 'XPC_FLAGS': '0x0', 18:44:05 INFO - 'XPC_SERVICE_NAME': '0', 18:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:05 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 18:44:05 INFO - Cleaning up... 18:44:05 INFO - Return code: 0 18:44:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:44:05 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')]} 18:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:05 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')]} 18:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:05 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:05 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 18:44:05 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 18:44:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:05 INFO - 'HOME': '/Users/cltbld', 18:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:05 INFO - 'LOGNAME': 'cltbld', 18:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:05 INFO - 'MOZ_NO_REMOTE': '1', 18:44:05 INFO - 'NO_EM_RESTART': '1', 18:44:05 INFO - 'PAGER': '/bin/cat', 18:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:05 INFO - 'PWD': '/builds/slave/test', 18:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:05 INFO - 'SHELL': '/bin/bash', 18:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:05 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:05 INFO - 'USER': 'cltbld', 18:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:05 INFO - 'XPC_FLAGS': '0x0', 18:44:05 INFO - 'XPC_SERVICE_NAME': '0', 18:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:05 INFO - Downloading/unpacking psutil>=3.1.1 18:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:44:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:44:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:44:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:44:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:09 INFO - Installing collected packages: psutil 18:44:09 INFO - Running setup.py install for psutil 18:44:09 INFO - building 'psutil._psutil_osx' extension 18:44:09 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 18:44:10 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 18:44:10 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 18:44:10 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 18:44:10 INFO - building 'psutil._psutil_posix' extension 18:44:10 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 18:44:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:44:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:44:10 INFO - ^ 18:44:10 INFO - 1 warning generated. 18:44:10 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 18:44:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:10 INFO - Successfully installed psutil 18:44:10 INFO - Cleaning up... 18:44:11 INFO - Return code: 0 18:44:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:44:11 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')]} 18:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:11 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')]} 18:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:11 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:11 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 18:44:11 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 18:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:11 INFO - 'HOME': '/Users/cltbld', 18:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:11 INFO - 'LOGNAME': 'cltbld', 18:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:11 INFO - 'MOZ_NO_REMOTE': '1', 18:44:11 INFO - 'NO_EM_RESTART': '1', 18:44:11 INFO - 'PAGER': '/bin/cat', 18:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:11 INFO - 'PWD': '/builds/slave/test', 18:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:11 INFO - 'SHELL': '/bin/bash', 18:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:11 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:11 INFO - 'USER': 'cltbld', 18:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:11 INFO - 'XPC_FLAGS': '0x0', 18:44:11 INFO - 'XPC_SERVICE_NAME': '0', 18:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:44:11 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 18:44:11 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 18:44:11 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 18:44:11 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 18:44:11 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 18:44:11 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 18:44:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:44:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:44:14 INFO - Installing collected packages: mozsystemmonitor 18:44:14 INFO - Running setup.py install for mozsystemmonitor 18:44:14 INFO - Successfully installed mozsystemmonitor 18:44:14 INFO - Cleaning up... 18:44:14 INFO - Return code: 0 18:44:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:44: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')]} 18:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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')]} 18:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:14 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:14 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 18:44:14 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 18:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:14 INFO - 'HOME': '/Users/cltbld', 18:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:14 INFO - 'LOGNAME': 'cltbld', 18:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:14 INFO - 'MOZ_NO_REMOTE': '1', 18:44:14 INFO - 'NO_EM_RESTART': '1', 18:44:14 INFO - 'PAGER': '/bin/cat', 18:44:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:14 INFO - 'PWD': '/builds/slave/test', 18:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:14 INFO - 'SHELL': '/bin/bash', 18:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:14 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:14 INFO - 'USER': 'cltbld', 18:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:14 INFO - 'XPC_FLAGS': '0x0', 18:44:14 INFO - 'XPC_SERVICE_NAME': '0', 18:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:14 INFO - Downloading/unpacking jsonschema==2.5.1 18:44:14 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 18:44:14 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 18:44:14 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 18:44:14 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 18:44:14 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 18:44:14 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 18:44:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:44:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:44:17 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 18:44:17 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 18:44:17 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 18:44:17 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 18:44:17 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 18:44:17 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 18:44:17 INFO - Downloading functools32-3.2.3-2.tar.gz 18:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:44:17 INFO - warning: no files found matching '*.txt' 18:44:17 INFO - no previously-included directories found matching 'build' 18:44:17 INFO - no previously-included directories found matching 'dist' 18:44:17 INFO - no previously-included directories found matching '.git*' 18:44:17 INFO - Installing collected packages: jsonschema, functools32 18:44:17 INFO - Running setup.py install for functools32 18:44:17 INFO - warning: no files found matching '*.txt' 18:44:17 INFO - no previously-included directories found matching 'build' 18:44:17 INFO - no previously-included directories found matching 'dist' 18:44:17 INFO - no previously-included directories found matching '.git*' 18:44:17 INFO - Successfully installed jsonschema functools32 18:44:17 INFO - Cleaning up... 18:44:17 INFO - Return code: 0 18:44:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:17 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:17 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 18:44:17 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 18:44:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:17 INFO - 'HOME': '/Users/cltbld', 18:44:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:17 INFO - 'LOGNAME': 'cltbld', 18:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:17 INFO - 'MOZ_NO_REMOTE': '1', 18:44:17 INFO - 'NO_EM_RESTART': '1', 18:44:17 INFO - 'PAGER': '/bin/cat', 18:44:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:17 INFO - 'PWD': '/builds/slave/test', 18:44:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:17 INFO - 'SHELL': '/bin/bash', 18:44:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:17 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:17 INFO - 'USER': 'cltbld', 18:44:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:17 INFO - 'XPC_FLAGS': '0x0', 18:44:17 INFO - 'XPC_SERVICE_NAME': '0', 18:44:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:44:18 INFO - Cleaning up... 18:44:18 INFO - Return code: 0 18:44:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:18 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:18 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 18:44:18 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 18:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:18 INFO - 'HOME': '/Users/cltbld', 18:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:18 INFO - 'LOGNAME': 'cltbld', 18:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:18 INFO - 'MOZ_NO_REMOTE': '1', 18:44:18 INFO - 'NO_EM_RESTART': '1', 18:44:18 INFO - 'PAGER': '/bin/cat', 18:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:18 INFO - 'PWD': '/builds/slave/test', 18:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:18 INFO - 'SHELL': '/bin/bash', 18:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:18 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:18 INFO - 'USER': 'cltbld', 18:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:18 INFO - 'XPC_FLAGS': '0x0', 18:44:18 INFO - 'XPC_SERVICE_NAME': '0', 18:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:18 INFO - Downloading/unpacking blobuploader==1.2.4 18:44:18 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 18:44:18 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 18:44:18 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 18:44:18 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 18:44:18 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 18:44:18 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 18:44:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:44:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:44:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:21 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 18:44:22 INFO - Downloading docopt-0.6.1.tar.gz 18:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:44:22 INFO - Installing collected packages: blobuploader, requests, docopt 18:44:22 INFO - Running setup.py install for blobuploader 18:44:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:44:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:44:22 INFO - Running setup.py install for requests 18:44:22 INFO - Running setup.py install for docopt 18:44:23 INFO - Successfully installed blobuploader requests docopt 18:44:23 INFO - Cleaning up... 18:44:23 INFO - Return code: 0 18:44:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:23 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')]} 18:44:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:23 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')]} 18:44:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:23 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:23 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 18:44:23 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 18:44:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:23 INFO - 'HOME': '/Users/cltbld', 18:44:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:23 INFO - 'LOGNAME': 'cltbld', 18:44:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:23 INFO - 'MOZ_NO_REMOTE': '1', 18:44:23 INFO - 'NO_EM_RESTART': '1', 18:44:23 INFO - 'PAGER': '/bin/cat', 18:44:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:23 INFO - 'PWD': '/builds/slave/test', 18:44:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:23 INFO - 'SHELL': '/bin/bash', 18:44:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:23 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:23 INFO - 'USER': 'cltbld', 18:44:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:23 INFO - 'XPC_FLAGS': '0x0', 18:44:23 INFO - 'XPC_SERVICE_NAME': '0', 18:44:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-0mKZ8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-5bkYlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-sj_LOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-_dBZnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-EneGFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:23 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-65kyZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-yGc37y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-G0EbvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-TUmtWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-pu3P3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-aMwZGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-aAkDQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-1h0iPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-2QYiTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:24 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-cdD7Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:25 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-qfO3dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:25 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-6Yc0Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:44:25 INFO - Running setup.py install for manifestparser 18:44:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:44:25 INFO - Running setup.py install for mozcrash 18:44:25 INFO - Running setup.py install for mozdebug 18:44:25 INFO - Running setup.py install for mozdevice 18:44:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:44:25 INFO - Running setup.py install for mozfile 18:44:25 INFO - Running setup.py install for mozhttpd 18:44:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Running setup.py install for mozinfo 18:44:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Running setup.py install for mozInstall 18:44:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Running setup.py install for mozleak 18:44:26 INFO - Running setup.py install for mozlog 18:44:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Running setup.py install for moznetwork 18:44:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:44:26 INFO - Running setup.py install for mozprocess 18:44:26 INFO - Running setup.py install for mozprofile 18:44:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozrunner 18:44:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Running setup.py install for mozscreenshot 18:44:27 INFO - Running setup.py install for moztest 18:44:27 INFO - Running setup.py install for mozversion 18:44:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:44:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:44:27 INFO - Cleaning up... 18:44:27 INFO - Return code: 0 18:44:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:27 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:27 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 18:44:27 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 18:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:27 INFO - 'HOME': '/Users/cltbld', 18:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:27 INFO - 'LOGNAME': 'cltbld', 18:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:27 INFO - 'MOZ_NO_REMOTE': '1', 18:44:27 INFO - 'NO_EM_RESTART': '1', 18:44:27 INFO - 'PAGER': '/bin/cat', 18:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:27 INFO - 'PWD': '/builds/slave/test', 18:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:27 INFO - 'SHELL': '/bin/bash', 18:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:27 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:27 INFO - 'USER': 'cltbld', 18:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:27 INFO - 'XPC_FLAGS': '0x0', 18:44:27 INFO - 'XPC_SERVICE_NAME': '0', 18:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-UTaCKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-qZzL5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-52qg1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-BX2Htj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ldbr6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-fEaOtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-LQNTTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-haNKnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:28 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)) 18:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:28 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-CoYJ7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-tE3jvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-u7fsBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-FDlFsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-f54UZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-6dYu1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-HhnVSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-OLKcLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:29 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)) 18:44:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:29 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-4jQlPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:29 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)) 18:44:29 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)) 18:44:29 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)) 18:44:29 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)) 18:44:29 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)) 18:44:29 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)) 18:44:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:44:29 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 18:44:29 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 18:44:29 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 18:44:29 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 18:44:29 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 18:44:29 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 18:44:32 INFO - Downloading blessings-1.6.tar.gz 18:44:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:44:32 INFO - Installing collected packages: blessings 18:44:32 INFO - Running setup.py install for blessings 18:44:32 INFO - Successfully installed blessings 18:44:32 INFO - Cleaning up... 18:44:32 INFO - Return code: 0 18:44:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:44:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:33 INFO - Reading from file tmpfile_stdout 18:44:33 INFO - Current package versions: 18:44:33 INFO - blessings == 1.6 18:44:33 INFO - blobuploader == 1.2.4 18:44:33 INFO - docopt == 0.6.1 18:44:33 INFO - functools32 == 3.2.3-2 18:44:33 INFO - jsonschema == 2.5.1 18:44:33 INFO - manifestparser == 1.1 18:44:33 INFO - mozInstall == 1.12 18:44:33 INFO - mozcrash == 1.0 18:44:33 INFO - mozdebug == 0.1 18:44:33 INFO - mozdevice == 0.49 18:44:33 INFO - mozfile == 1.2 18:44:33 INFO - mozhttpd == 0.7 18:44:33 INFO - mozinfo == 0.9 18:44:33 INFO - mozleak == 0.1 18:44:33 INFO - mozlog == 3.4 18:44:33 INFO - moznetwork == 0.27 18:44:33 INFO - mozprocess == 0.25 18:44:33 INFO - mozprofile == 0.28 18:44:33 INFO - mozrunner == 6.13 18:44:33 INFO - mozscreenshot == 0.1 18:44:33 INFO - mozsystemmonitor == 0.3 18:44:33 INFO - moztest == 0.8 18:44:33 INFO - mozversion == 1.4 18:44:33 INFO - psutil == 3.1.1 18:44:33 INFO - requests == 1.2.3 18:44:33 INFO - wsgiref == 0.1.2 18:44:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:33 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')]} 18:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:33 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')]} 18:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:33 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:33 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 18:44:33 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 18:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:33 INFO - 'HOME': '/Users/cltbld', 18:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:33 INFO - 'LOGNAME': 'cltbld', 18:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:33 INFO - 'MOZ_NO_REMOTE': '1', 18:44:33 INFO - 'NO_EM_RESTART': '1', 18:44:33 INFO - 'PAGER': '/bin/cat', 18:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:33 INFO - 'PWD': '/builds/slave/test', 18:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:33 INFO - 'SHELL': '/bin/bash', 18:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:33 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:33 INFO - 'USER': 'cltbld', 18:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:33 INFO - 'XPC_FLAGS': '0x0', 18:44:33 INFO - 'XPC_SERVICE_NAME': '0', 18:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 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)) 18:44:33 INFO - Cleaning up... 18:44:33 INFO - Return code: 0 18:44:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:44:33 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')]} 18:44:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:33 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')]} 18:44:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:33 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 0x104a5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd224b66e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, '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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 18:44:33 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 18:44:33 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 18:44:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:33 INFO - 'HOME': '/Users/cltbld', 18:44:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:33 INFO - 'LOGNAME': 'cltbld', 18:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:33 INFO - 'MOZ_NO_REMOTE': '1', 18:44:33 INFO - 'NO_EM_RESTART': '1', 18:44:33 INFO - 'PAGER': '/bin/cat', 18:44:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:33 INFO - 'PWD': '/builds/slave/test', 18:44:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:33 INFO - 'SHELL': '/bin/bash', 18:44:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:33 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:33 INFO - 'USER': 'cltbld', 18:44:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:33 INFO - 'XPC_FLAGS': '0x0', 18:44:33 INFO - 'XPC_SERVICE_NAME': '0', 18:44:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:44:33 INFO - Cleaning up... 18:44:33 INFO - Return code: 0 18:44:33 INFO - Running post-action listener: _resource_record_post_action 18:44:33 INFO - Running post-action listener: _start_resource_monitoring 18:44:33 INFO - Starting resource monitoring. 18:44:33 INFO - [mozharness: 2017-03-28 01:44:33.751192Z] Finished create-virtualenv step (success) 18:44:33 INFO - [mozharness: 2017-03-28 01:44:33.752177Z] Running install step. 18:44:33 INFO - Running pre-action listener: _resource_record_pre_action 18:44:33 INFO - Running main action method: install 18:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:34 INFO - Reading from file tmpfile_stdout 18:44:34 INFO - Detecting whether we're running mozinstall >=1.0... 18:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:44:34 INFO - Reading from file tmpfile_stdout 18:44:34 INFO - Output received: 18:44:34 INFO - Usage: mozinstall [options] installer 18:44:34 INFO - Options: 18:44:34 INFO - -h, --help show this help message and exit 18:44:34 INFO - -d DEST, --destination=DEST 18:44:34 INFO - Directory to install application into. [default: 18:44:34 INFO - "/builds/slave/test"] 18:44:34 INFO - --app=APP Application being installed. [default: firefox] 18:44:34 INFO - mkdir: /builds/slave/test/build/application 18:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:44:54 INFO - Reading from file tmpfile_stdout 18:44:54 INFO - Output received: 18:44:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:44:54 INFO - Running post-action listener: _resource_record_post_action 18:44:54 INFO - [mozharness: 2017-03-28 01:44:54.660312Z] Finished install step (success) 18:44:54 INFO - [mozharness: 2017-03-28 01:44:54.660466Z] Running run-tests step. 18:44:54 INFO - Running pre-action listener: _resource_record_pre_action 18:44:54 INFO - Running main action method: run_tests 18:44:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:44:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:44:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:44:54 INFO - 2017-03-27 18:44:54.675 screenresolution[1632:7556] starting screenresolution argv=screenresolution get 18:44:54 INFO - 2017-03-27 18:44:54.677 screenresolution[1632:7556] Display 0: 1600x1200x32@60 18:44:54 INFO - 2017-03-27 18:44:54.683 screenresolution[1633:7559] starting screenresolution argv=screenresolution list 18:44:54 INFO - Available Modes on Display 0 18:44:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:44:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:44:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:44:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:44:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:44:54 INFO - Intel Iris: 18:44:54 INFO - Chipset Model: Intel Iris 18:44:54 INFO - Type: GPU 18:44:54 INFO - Bus: Built-In 18:44:54 INFO - VRAM (Dynamic, Max): 1536 MB 18:44:54 INFO - Vendor: Intel (0x8086) 18:44:54 INFO - Device ID: 0x0a2e 18:44:54 INFO - Revision ID: 0x0009 18:44:54 INFO - Displays: 18:44:54 INFO - Display: 18:44:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:44:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:44:54 INFO - Display Serial Number: 1600x1200 60 18:44:54 INFO - Main Display: Yes 18:44:54 INFO - Mirror: Off 18:44:54 INFO - Online: Yes 18:44:54 INFO - Rotation: Supported 18:44:54 INFO - Return code: 0 18:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:44:54 INFO - Python 2.7.3 18:44:54 INFO - Return code: 0 18:44:54 INFO - grabbing minidump binary from tooltool 18:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:44:54 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 0x10483dd30>, '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 0x104764db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104832be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:44:54 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 18:44:54 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 18:44:54 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 18:44:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:44:55 INFO - Return code: 0 18:44:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:44:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:44:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:44:55 INFO - ENV: RUST_BACKTRACE is now 1 18:44:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:44:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 18:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0359 --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 18:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w4NiV71Ea6/Render', 18:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:44:55 INFO - 'HOME': '/Users/cltbld', 18:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:44:55 INFO - 'LOGNAME': 'cltbld', 18:44:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:44:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:55 INFO - 'MOZ_NO_REMOTE': '1', 18:44:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:44:55 INFO - 'NO_EM_RESTART': '1', 18:44:55 INFO - 'PAGER': '/bin/cat', 18:44:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:55 INFO - 'PWD': '/builds/slave/test', 18:44:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:44:55 INFO - 'RUST_BACKTRACE': '1', 18:44:55 INFO - 'SHELL': '/bin/bash', 18:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nz3rxZa4t5/Listeners', 18:44:55 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 18:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:44:55 INFO - 'USER': 'cltbld', 18:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:55 INFO - 'XPC_FLAGS': '0x0', 18:44:55 INFO - 'XPC_SERVICE_NAME': '0', 18:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:44:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 18:44:55 INFO - mozversion application_buildid: 20170327162522 18:44:55 INFO - mozversion application_changeset: f7f7ff518802ccc22f984cad4d2bbde47666d88c 18:44:55 INFO - mozversion application_display_name: Nightly 18:44:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:44:55 INFO - mozversion application_name: Firefox 18:44:55 INFO - mozversion application_remotingname: firefox 18:44:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:44:55 INFO - mozversion application_vendor: Mozilla 18:44:55 INFO - mozversion application_version: 55.0a1 18:44:55 INFO - mozversion platform_buildid: 20170327162522 18:44:55 INFO - mozversion platform_changeset: f7f7ff518802ccc22f984cad4d2bbde47666d88c 18:44:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:44:55 INFO - mozversion platform_version: 55.0a1 18:44:55 INFO - using testdate: 1490665495 18:44:55 INFO - actual date: 1490665495 18:44:55 INFO - starting webserver on 'localhost:49241' 18:44:55 INFO - SUITE-START | Running 2 tests 18:44:55 INFO - TEST-START | dromaeo_css 18:44:55 INFO - operating with platform_type : mac_ 18:44:55 INFO - Initialising browser for dromaeo_css test... 18:44:55 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile http://localhost:49241/getInfo.html) 18:44:58 INFO - PID 1643 | 2017-03-27 18:44:58.071 plugin-container[1645:7734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924b, name = 'com.apple.tsm.portname' 18:44:58 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:58 INFO - PID 1643 | 2017-03-27 18:44:58.076 plugin-container[1645:7734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9617, name = 'com.apple.CFPasteboardClient' 18:44:58 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:44:58 INFO - PID 1643 | 2017-03-27 18:44:58.076 plugin-container[1645:7734] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:44:58 INFO - PID 1643 | __metrics Screen width/height:1600/1200 18:44:58 INFO - PID 1643 | colorDepth:24 18:44:58 INFO - PID 1643 | Browser inner width/height: 1024/689 18:44:58 INFO - PID 1643 | __metrics 18:44:58 ERROR - PID 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 18:44:58 ERROR - PID 1643 | 1490665498239 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile/extensions/talos-powers@TalosPowersService.js:55:9 18:44:59 INFO - TEST-INFO | 1643: exit 0 18:44:59 INFO - Browser initialized. 18:44:59 INFO - Running cycle 1/1 for dromaeo_css test... 18:44:59 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpx_PBfp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:01 INFO - PID 1649 | 2017-03-27 18:45:01.702 plugin-container[1650:7925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 18:45:01 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:01 INFO - PID 1649 | 2017-03-27 18:45:01.703 plugin-container[1650:7925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 18:45:01 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:01 INFO - PID 1649 | 2017-03-27 18:45:01.703 plugin-container[1650:7925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:45:11 INFO - PID 1649 | RSS: Main: 229453824 18:45:11 INFO - PID 1649 | 18:48:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 18:48:17 INFO - PID 1649 | RSS: Main: 252207104 18:48:17 INFO - PID 1649 | 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 18:49:59 INFO - PID 1649 | 18:49:59 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:49:59 INFO - PID 1649 | Full stack: 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 18:49:59 INFO - PID 1649 | 18:49:59 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:49:59 INFO - PID 1649 | Full stack: 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 18:49:59 INFO - PID 1649 | 18:49:59 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:49:59 INFO - PID 1649 | Full stack: 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | ************************* 18:49:59 INFO - PID 1649 | A coding exception was thrown and uncaught in a Task. 18:49:59 INFO - PID 1649 | 18:49:59 ERROR - PID 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:49:59 INFO - PID 1649 | Full stack: 18:49:59 INFO - PID 1649 | ************************* 18:51:20 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:51:20 INFO - PID 1649 | RSS: Main: 258195456 18:51:20 INFO - PID 1649 | 18:54:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:54:23 INFO - PID 1649 | RSS: Main: 273051648 18:54:23 INFO - PID 1649 | 18:57:25 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:57:26 INFO - PID 1649 | RSS: Main: 265601024 18:57:26 INFO - PID 1649 | 19:00:29 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 19:00:29 INFO - PID 1649 | RSS: Main: 275828736 19:00:29 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 19:03:32 INFO - PID 1649 | RSS: Main: 270409728 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | __start_tp_report 19:03:32 INFO - PID 1649 | _x_x_mozilla_page_load 19:03:32 INFO - PID 1649 | _x_x_mozilla_page_load_details 19:03:32 INFO - PID 1649 | |i|pagename|runs| 19:03:32 INFO - PID 1649 | |0;dojo.html;2865.4269146170764;3580.91047723807;3593.838277277522;3629.981850090749;3651.196736717922;4947.529984651462;6115.296740874801;6122.2041134652545;6233.844153896155;6239.6880155992185;6135.81592552223;6143;6147.108669242957;6153.569250152491;6208.255009398878;4968.403791545002;5589.832305030855;5680.829575112753;5682.318121825366;5924.140999555084;4897.510248975092;5568.415316391782;5696.946064977992;5706.28671416073;5865.266841644794;5551.278333816578;5586.273784408041;5638.633488823213;5752.165935939307;5791.044477661202;5605.383407825136;5614.719264036782;5721.713914304268;5755.539556835443;5764.538836893081;5524.1989911463015;5610.579206559483;5612.017896868032;5636.351819540723;5636.379998200195;5152.6650767699975;6175.228096487939;6241.407066328737;6261.530385221081;6266.87466250677;6478.352164783531;6576.6382848943285;6637.502187335969;6727.192736871607;6745.561538500031;5595.804146854881;6787.55880867747;6816.965915170442;6828.180618325783;6831.931680683179;5759.020966435716;6293.905591416133;6300.432961033529;6351.142595749561;6363.363663633646;5617.21359009739;6596.80209593713;6688.966555167291;6790.388865002174;6817.965910170417;6411.4229719325485;6542.967285163544;6590.7693230736695;6611.107500487469;6652.334766523406;5401.351837779492;5419.783208671688;6778.186617605918;6825.1809782825385;6861.691223894937;6641.369069938348;6699.665016749143;6739.022841687928;6740.4270636995425;6758.155230596176;5600.635958662674;6463.6768161591735;6576.473882089422;6770.898436523456;6779.457643388517;5337;5337.572994160458;5363.329583802025;5363.383210930715;5372.704501252469;4030.3349947258375;4814.253790662453;4880.292357608127;4883.072216278895;4897.902041959141;6312.337204593543;6339.746410143634;6340.7463701451425;6348.77779277723;6374.298827129012;6541.6075035498025;6841.2132604750095;6869.553479023847;6869.931300687029;6873.793786186422;6212.316645169028;6249.125122482856;6304.085907543381;6340.682965851689;6363.745450182034;6062.787802426894;6095.573309868267;6100.69496525172;6105.049782388334;6107.572469927151;6871.9656401717675;6948.6178260196175;6959.130108736408;6972;6988.44092472601;6547.770828020996;6652.7006284716335;6737.831554211184;6810.216825064983;6817.5568588041615;6590.373914478117;6885.862282754417;6950.860982780215;6959.26927672587;6960.617166055916;6689.163854518185;6690.13028306317;6709.329067093252;6714.294999025227;6734.292899245705;6529.9020514691365;6582.670866456562;6669.09967154428;6731.259561448139;6829.317068293131;5397.244385786072;5870.324912635102;6264.809686159615;6598.27418983921;6602.141721576164;6191.597546159485;6377.0753240778895;6597.472202223715;6608.603483790988;6620.172478440195;6365;6405.679716014088;6435.388638079375;6441.484681225585;6446.548741588043;5493.862653433696;6504.577202482013;6599.670016499252;6601.867962640817;6607.669616519251;6199.473044791243;6432.774852880127;6458.257300410513;6459.063435801876;6475.838104047437 19:03:32 INFO - PID 1649 | |1;ext.html;742.9190527782077;805.1063319715117;827.9751607451776;1170.1750266062427;1273.624280837153;31998.520022199686;37606;38685.613143868555;38699;38815.80592097039;39378.21243575134;39821;39863;39910.800445997804;39950.00124996869;7686.346660533862;8212.055613604438;8356.2897153742;8577.27093197079;8826.029136794947;7774.727884524043;8520.977482702085;8592.097829727882;8715.825683486326;8718.34612404069;8672.262857657106;8705.042445331008;8759.693410730626;8851.424657397281;8862.512561809099;8763.255123314493;8806.471611703319;8820.823583528325;8823.029466758651;8824;8634.956825215897;8651.524166170859;8657.22085012352;8668.696595619185;8689.391742578022;31211.843940780378;38659.42010869839;38751.612483875084;38800.80599596984;38928;2987.611610490644;3019.773516986235;3033.969660303391;3034.9544756828664;3047.801892876978;28408;28583.571246431322;28694.85220591174;28865.85567072151;29032.983845565304;2760.1029665358637;2782.5949084855342;2817.7886658500693;2840.4887120318335;2854.215090850012;246888;250988;280383.59808201034;281865;283543;47093;47096.764516177886;47352.5264747355;47376.8155796101;47478.76260618744;48764;50629;51083.97832043413;51257.71855703562;51289.743551282;12005.579804706793;14659.633509162357;14667.926660366844;14678;14696.265186740835;11005.944970275257;11020.622422197226;11824.23142495735;11983.28100313984;11983.700407489709;13834.23911684867;13844.584662460142;13853.722925541433;13859.861401386059;13963.441462341596;5153.7165455900285;6119.877602447926;6124.785632502841;6135.294441139237;6149.600275982047;855.3499340501264;926.3006430145198;948.8529277961927;954.9904500954901;996.4370130876032;19275.22899084021;23353.949072291787;24575.87712061428;24617;24695.50608987846;957.3346524165638;1056.8837427882927;1068.150820098024;1073.1951036722458;1089.7493576477455;11942.522299107939;12486.75026499465;12500.312482813533;12502.312372819582;12519.937400313122;480248.59875701094;482369;482408;483419;483611;55614;55989.72005139948;56424;56434;56478.87042259132;1493.746063169242;1503.9699206015819;1550.58909389012;1557.1824791984072;1565.053142848583;21060.7363558187;22647.508774034515;23819.566142909258;23946.7694273622;24168.154114605903;37051.62948370482;39145.41281880831;39389.01527461837;39402;39484.40773388459;445332;446741;447266;447919;448295;32629.34741305113;33300.16749581183;34437;34510;34536.82731586426;41321.586784131774;41327.79336103421;41503;41757.747267582025;42035.36946945737;27874.907903383053;28970.42059158763;29148.417031658824;29757.256068598457;30445.847770761004;29537.2615684601;29840.850795745882;30336;30343.696563034086;30677;33696.3260734779;35541.46687799633;36188.63811361958;36312.4553131698;36487.08782280464;26219.606705899045;27158.321041974108;27818.304542386602;30079.646415911775;31087.2298389513;22062.558748825253;22247.332580022627;22879.08483660635;24091.75908240965;24289.51420971606 19:03:32 INFO - PID 1649 | |2;jquery.html;386.4319450407899;419.230711644133;431.37019950871735;436.6070536517136;455.52625269719493;19757.506062348468;20588.48528786781;21782.673259901116;21893.233736819213;21940;21743.91280435976;23223.419414514647;23542.058317667317;23658.881705591448;23769.76230237697;6171.0743388491865;6450.096986421881;6473.802346565901;6482.319356467574;6490.643014634194;5174.301469301652;6166.7533298667995;6299.275583307933;6325.746970121189;6373.94829853076;5962;6267.749290028393;6324.841878953017;6334.398232167937;6351.079093531435;5299.311089558352;6192.225971753531;6252.562320637558;6267.185265915447;6278.623282603058;4983;5463.31708536433;5505.862353441196;5571.164325351189;5624.015797235467;27098.729012709817;27127.593086103527;27626.447471050466;27759.861200693867;28677.713222867922;2031.7765045844944;2036.4806974221626;2056.4550394145563;2061.0210150178696;2090.216168936649;15389.692206155925;16385.59036024097;16556.50330490087;16596.917015414965;17239.82760172407;1537.0009493828989;1578.1041788318335;1607.710612089835;1615.935362585495;1665.6668666266796;113191.86808131896;138644;141864.87202691866;145048;151956;10418.385150301716;10781.568737250422;11882.524699011943;12628.558000469939;12651.810222846665;13405.463781448632;15521.913466057493;15933.601659958595;16001.919990399974;16328.428505002268;7610.505317154367;8081.6363263653275;8714.9977752558;9242.445453272825;9312.394694344981;8869.379143459895;8879.955600222087;8880.422772519765;9060.637574496946;9063.59213835379;7106.862901935666;7503.8499230015095;7984.361251099898;8118.959405202936;8155.918440815634;2199.988005517448;2203.6584329428965;2206.4925067234303;2211.7677643847487;2384.1536254629705;856.2208390364738;922.2621902478124;946.6260826973307;985.7683444390562;998.9900100999042;21337;21514.56970860574;21519.924003799748;21580.46048848791;21601.02795374212;870.3037569944146;966.1063516247443;981.1071924548627;1008.8083264179783;1013.7110923386799;10153.086222240034;10997.560097596006;11118.444077796077;11151.330920144817;11184.38485883268;583155;613237;657687;660896;685889;113719;141570.29214854067;144306;156163;157177;1130.4404319861721;1150.8388825564427;1190.5470563724004;1229.766344394562;1242.6955395927876;21325;21490.78509214888;21728.924219392335;21735.456613584785;21739.782602173775;63350;63449;64094.67952660393;64641.67679161574;64914.05257842272;70738.93891591733;91256.08743912476;92474;92639;92733.07266927244;8506.023975974125;8608.053114157563;8649.783755405913;8769.035406105195;8785.692500762698;12521.248725076524;13089.803652945024;13498.730025399633;13652.522161724291;14090.295485225493;11200.047995920439;11334.146390227033;11673.766524669289;11812.3503207321;12003.939980300043;10803.72990675208;10840.078593319657;10952.945235273774;11144.996950274513;11242.887571124511;11683.357415342238;11971.760564788481;11997.880021199677;12014.63956081319;12086.274823510617;7165.964170179116;8557.015943166616;8606.752020956801;8726.345524085593;8897.822043558963;11256.83114753263;11591.76816463683;11789.528418863492;11904.464299106388;11930.343831089373 19:03:32 INFO - PID 1649 | |3;mootools.html;1368.698886245026;1405.7188562287538;1416.3555582210083;1421.848302874671;1445.2990299704645;10672.199585031125;10935;11107.944460277697;11171.162162837789;11291.830622540669;11032.338059716423;11185.608503702371;11205;11206.103511719064;11213.215074944757;2458.582041053021;2483;2492.239866840613;2495.214007587613;2498.837575557592;2407.6629271901948;2443.2792326263707;2466.691663542057;2468.4445999650115;2475.504899020194;2426.575349313872;2430.404550885036;2451.0685939343116;2465.186488458813;2479.1075212923333;2353.6234202527685;2359.044586942291;2372.3001714494267;2372.54921564903;2459.446624509489;2353.458704497958;2440.6095024795945;2444.4744379958393;2453.0800949643885;2460.4709987352803;9354.953225233898;11042.834357484644;11173.329600224013;11226.214165008452;11231.775364492745;6279.277883043463;6465.418112369863;6506.707198176094;6517;6524.282328943812;3831.9616803832164;3988.720789544733;4000;4046.898827529306;4104.240715467648;1149.9080073594093;1154.809456439695;1181.6809461445448;1186.649938268213;1203.8615559210627;13785.931070344684;14159;15422.768658470131;15489;15535.223238838013;5311.867203319871;5495.670259784346;5509.917351239735;5511.559075273968;5523.392426833045;11711.88288117108;11736.88263117375;11760.117991150526;11784.351860647746;11818.881811181951;6132.294786099566;6309.400606942333;6310.368963103745;6319.8104056878365;6329.367063293634;5958.433948774859;5997.340292567814;5998.4601385875485;6071.483923866433;6148.354422785633;4321.719088259253;4520.299353600197;4587.220172570671;4610.95389046112;4668.393108895822;1177.1230433327055;1429.6783223774567;1437.1460815548035;1442.185165381556;1461.7222727681706;768.8654485465021;777.1296148313812;794.3367288314207;800.7117437722416;828.4656396624143;6248.125262463259;6479.8056058318325;6736.865262694817;6774.254831968479;6873.4157596603845;848.2874385516072;886.835935351962;894.695803426838;919.5769945824995;964.6479035152119;3588.8923332300074;4048.716589838742;4171.874843754692;4219.936700949488;4293.720908140961;11294.887051129384;13176.407061682246;13419.463221471227;13964.092333998258;14038.227897465737;10147.543360548645;13637;13657.931710341385;13708.314584270747;13993.72012559743;9941.751456213653;10207.846882296772;10239.59041638341;10250;10276.948615256875;5588.776448941997;6321.652309123042;6550.672466376567;6834.419074378733;6856.794296170923;11645.59240426581;12208.267503949792;12337.951274141798;12352.25886446816;12489.000879929403;11731.120165987755;11741.471633776671;12648.051396145142;12681.429335679732;12801.295928723637;9086;9243.907560924305;9350.625974960925;9356.204722598655;9369.578368973276;9978.501074946078;10224.59101635956;10299.330543514647;10317.587296508056;10336.276460647983;8598.269147122564;8757.255633270988;8837.646494140163;8893.866592001255;8952.01527831951;8576.142385761374;8614.65541378338;8639.179277968582;8639.784005399915;8726.912730872864;7802.336801371947;7824.334931530656;7896.012998375203;7905.407094467823;7936.920630793618;5856.502197313276;5876.882462350814;5974;6041.308433638588;6084.661374497603;5786.884262314646;5959.731812068554;6058.151858739692;6163.84590385244;6167.352427994996 19:03:32 INFO - PID 1649 | |4;prototype.html;410.2779108768567;410.767916127388;413.13242191398047;414.39912127415215;419.22861934041356;6282.68586570672;6353.52348573857;6396.360363963601;6451.322611125829;6531.477481801457;5116.5139311765415;6211.378862113786;6328.208973878265;6367.33143019982;6375.713092910819;4781.593564547018;4860.684055536397;4942.406911170665;4952.430470495903;5016.297718319438;4113.177364527107;4172.916541669165;4899.8040078396825;5018.272350509174;5026.874328141789;4987.650864439473;4995.950040499603;5013.774380152902;5020;5036.6222533309965;4270.743755374688;4467.3299005149165;4874.512548745132;4953.430355509108;4993.051320249141;4365.80353884076;4370.934435983463;4387.912241755179;4391.121775644884;4404.823807047715;6035.577509574332;6286.49708023357;6291.968540157316;6302.275238347557;6309.432151106376;5734.784933074187;5804.8839023219725;5840.3575606683235;5843.912341314884;5846.941530584682;6919.95861220936;6922;6929.480288978297;6939;6939.722411103549;4179.310413781723;4735.408073990751;4884.267359896008;4939.678920870132;4958.404991401055;6889.655517224169;7079.469039821983;7170.3905168060255;7267.382272506843;7361.742339018162;4380.9123817524105;4838.7338696371335;4979.278004689372;5010.874728131826;5063.949360506422;5088.72012039334;5136.7928536793825;5145.331106956147;5148.794048238028;5155.690658560498;4875.634327425457;5561.138023606348;5736.1682556029145;5739.311282646109;5777.508911742465;6722.899156512656;6882.5546635206165;6897.137348338435;6927.896081558781;6936.826579335557;1706.634692246198;1744.7382892565963;1751.2294590380193;1755.683976884173;1763.3916298876868;1003.3277703938232;1114.225613198819;1122.854028976228;1170.7658468306372;1200.2978257719158;5692.6299790513485;6425.807225783234;6443.162388889415;6462.32145624712;6467.159269294962;6404.743810247538;6548.345165483509;7095.687297849811;7192.985963930108;7194.56108778236;4956.553910148104;6117.847053823601;6181.289151747516;6303.212098487689;6321.841953951096;3642.1987162824134;3720.925581488355;3724.7020238381406;3727.4589140316807;3823.847046118124;6046.546509011841;6677.799666010027;6921.965390173017;7250.818729531804;7274;6182.103594978703;6371.267304259976;6908.549204667076;7078.75224367155;7274.345308922222;5200.97399513;6321.336259692666;6321.810345689545;7156.141263048572;7290.781276561712;5678.2050512929045;6200.534959878118;6332.543514991486;6884.862302753817;7202.53183543068;5370.624056316176;6271.811845644638;7060.788176354512;7081.6459177039915;7101.893471597827;6777.356151165632;7244.89132663;7332.266773322625;7378.778636640909;7385.076865391826;4205.474315710536;4871.415430148263;5027.321311622884;5047.570956468741;5090.847274581768;6987.650617469005;7046.189688185924;7123.216446191019;7141.607211603412;7164.426845852215;6813.591184528944;6854.721864164665;6935.167779866449;6935.479839011993;6938.687759050956;6398.904016439664;6552.443042341454;6753.628550429577;6818.18181818185;6891.931080689129;6615.140031796028;6642.667866606554;6672.266050734318;6744.393004629607;6746.470073722354;5649.378568357395;6450.41946224842;6822.795316140524;6852.349026842442;6898.103246577913;6654.803914708232;6657.900131497934;6666.400023997863;6675.399214070757;6725.764598239038 19:03:32 INFO - PID 1649 | |5;yui.html;604.9811440909859;681.1689738519007;698.7379732600278;725.92377800331;731.0423345417504;14855;17278.654426911453;18237.81762182378;18341.358052468167;18345.35791247305;16303.91848040761;17946.461606151803;18072.909635451804;18164.727529087075;18204.817951820478;839.2866063845744;944.2964991081636;969.8351280282334;971.7036303927299;973.2360097323602;834.3908946469081;923.4367036107968;940.3887473142444;963.3834346018554;964.0889359555223;991.8660980767612;995.0696099147284;995.1938929467119;995.2187532786763;1013.8935411781767;911.3028533172129;978.5841017567541;990.1633120013577;993.6671215142936;1013.6300250408584;729.6388287797521;751.8834580640009;762.5653377574785;764.228129589119;764.7132325377984;13566.253856037914;13673.863261367458;13829.51596694111;15011.924940375338;16879.915600422042;14703.411863525445;14704.632384190374;14814.777778333333;14898.702025959528;15121.470748523749;8837.002512725994;9672.661456848977;11384.943075284653;11817.527298908033;11945.104117191246;3051.298201413665;3059.495183294776;3071.3089554850203;3074.5234488654296;3179.6343420506473;81030.97422564424;102664;106199;106703.46648266786;109777;11302.886971130183;11437.199396042364;11550.306981581132;11716;11836.48262329055;23911.52176956451;24310.878445607657;24317.148899788423;24488.510229795305;24532.386690332885;7787.5716835574585;7797.844043119106;7848.803779905548;7980.920190798134;8020.157883422274;3971.7612359690256;4045.7977101144825;4064.248114098901;4067.7559346439307;4074.266632006267;4049;4080.5717998700216;4123.2165888481095;4132.607402296777;4138.7997480730855;739.8631253218172;782.8943092682415;782.9391174957964;795.8328750962363;810.9635066422026;542.4873494547614;575.2378099018816;580.4050847880872;582.4175824175824;582.9533637309005;9110.635574577076;10019;10025.14786243163;10054.34646747959;10115.696529104138;540.6891037653287;543.7308532276548;582.3041465448748;589.2251689028914;589.7169358707758;4205.75930100621;4487.41663583732;4554.817807287738;4661.813527458931;4670.556297151765;103895.4805225969;104720;106516;108068.45965770121;110596.89403106026;72616.63691681508;73699;77300.61349693328;89274;91382.54308728414;66003.66998165075;67228.6638566804;67623.64752704918;68171.3182868165;68581.65709171422;8012.214773111612;10220.49777609519;10593.417362045162;10694.19793515505;10740.462976851282;36988.26023479569;37635.870923872324;37777.81111094427;38046.239075218895;38540.42189367105;49643.01427942842;49985.750356241384;50231.748841257024;50700.239496406844;51321;11635.360055197045;12409.565665201675;12996.220226786423;13211.07522473456;13487.662808429868;11255.36797164423;11707.356095414834;13982.783959689124;14077.542164607019;14314.928425357806;10588.629397971034;10715.892841071489;12943.417546210254;13127.277999710108;13152.145110567782;10185.388876667424;12186.634400967985;12504.374781261082;12842.165259258118;12991.220526768046;12501.981610653153;12748.61754147377;12839.679008024874;12988.93505532504;13630.727385452434;9167;9225.677101301691;9316.767080823034;9346.766330841783;9860.359076659994;9043.68347107848;9112.95443522778;10369.9481502592;10535.473226338805;10565.841512377474 19:03:32 INFO - PID 1649 | __end_tp_report 19:03:32 INFO - PID 1649 | __start_cc_report 19:03:32 INFO - PID 1649 | _x_x_mozilla_cycle_collect,445 19:03:32 INFO - PID 1649 | __end_cc_report 19:03:32 INFO - PID 1649 | __startTimestamp1490666612591__endTimestamp 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | ------- Summary: start ------- 19:03:32 INFO - PID 1649 | Number of tests: 6 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6146.89 Median:6351.14 stddev:739.95 (11.7%) stddev-sans-first:696.01 19:03:32 INFO - PID 1649 | Values: 2865.4 3580.9 3593.8 3630.0 3651.2 4947.5 6115.3 6122.2 6233.8 6239.7 6135.8 6143.0 6147.1 6153.6 6208.3 4968.4 5589.8 5680.8 5682.3 5924.1 4897.5 5568.4 5696.9 5706.3 5865.3 5551.3 5586.3 5638.6 5752.2 5791.0 5605.4 5614.7 5721.7 5755.5 5764.5 5524.2 5610.6 5612.0 5636.4 5636.4 5152.7 6175.2 6241.4 6261.5 6266.9 6478.4 6576.6 6637.5 6727.2 6745.6 5595.8 6787.6 6817.0 6828.2 6831.9 5759.0 6293.9 6300.4 6351.1 6363.4 5617.2 6596.8 6689.0 6790.4 6818.0 6411.4 6543.0 6590.8 6611.1 6652.3 5401.4 5419.8 6778.2 6825.2 6861.7 6641.4 6699.7 6739.0 6740.4 6758.2 5600.6 6463.7 6576.5 6770.9 6779.5 5337.0 5337.6 5363.3 5363.4 5372.7 4030.3 4814.3 4880.3 4883.1 4897.9 6312.3 6339.7 6340.7 6348.8 6374.3 6541.6 6841.2 6869.6 6869.9 6873.8 6212.3 6249.1 6304.1 6340.7 6363.7 6062.8 6095.6 6100.7 6105.0 6107.6 6872.0 6948.6 6959.1 6972.0 6988.4 6547.8 6652.7 6737.8 6810.2 6817.6 6590.4 6885.9 6950.9 6959.3 6960.6 6689.2 6690.1 6709.3 6714.3 6734.3 6529.9 6582.7 6669.1 6731.3 6829.3 5397.2 5870.3 6264.8 6598.3 6602.1 6191.6 6377.1 6597.5 6608.6 6620.2 6365.0 6405.7 6435.4 6441.5 6446.5 5493.9 6504.6 6599.7 6601.9 6607.7 6199.5 6432.8 6458.3 6459.1 6475.8 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:53164.83 Median:36250.55 stddev:109273.53 (301.4%) stddev-sans-first:109509.19 19:03:32 INFO - PID 1649 | Values: 742.9 805.1 828.0 1170.2 1273.6 31998.5 37606.0 38685.6 38699.0 38815.8 39378.2 39821.0 39863.0 39910.8 39950.0 7686.3 8212.1 8356.3 8577.3 8826.0 7774.7 8521.0 8592.1 8715.8 8718.3 8672.3 8705.0 8759.7 8851.4 8862.5 8763.3 8806.5 8820.8 8823.0 8824.0 8635.0 8651.5 8657.2 8668.7 8689.4 31211.8 38659.4 38751.6 38800.8 38928.0 2987.6 3019.8 3034.0 3035.0 3047.8 28408.0 28583.6 28694.9 28865.9 29033.0 2760.1 2782.6 2817.8 2840.5 2854.2 246888.0 250988.0 280383.6 281865.0 283543.0 47093.0 47096.8 47352.5 47376.8 47478.8 48764.0 50629.0 51084.0 51257.7 51289.7 12005.6 14659.6 14667.9 14678.0 14696.3 11005.9 11020.6 11824.2 11983.3 11983.7 13834.2 13844.6 13853.7 13859.9 13963.4 5153.7 6119.9 6124.8 6135.3 6149.6 855.3 926.3 948.9 955.0 996.4 19275.2 23353.9 24575.9 24617.0 24695.5 957.3 1056.9 1068.2 1073.2 1089.7 11942.5 12486.8 12500.3 12502.3 12519.9 480248.6 482369.0 482408.0 483419.0 483611.0 55614.0 55989.7 56424.0 56434.0 56478.9 1493.7 1504.0 1550.6 1557.2 1565.1 21060.7 22647.5 23819.6 23946.8 24168.2 37051.6 39145.4 39389.0 39402.0 39484.4 445332.0 446741.0 447266.0 447919.0 448295.0 32629.3 33300.2 34437.0 34510.0 34536.8 41321.6 41327.8 41503.0 41757.7 42035.4 27874.9 28970.4 29148.4 29757.3 30445.8 29537.3 29840.9 30336.0 30343.7 30677.0 33696.3 35541.5 36188.6 36312.5 36487.1 26219.6 27158.3 27818.3 30079.6 31087.2 22062.6 22247.3 22879.1 24091.8 24289.5 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:38450.15 Median:2209.13 stddev:107594.18 (4870.4%) stddev-sans-first:107858.05 19:03:32 INFO - PID 1649 | Values: 386.4 419.2 431.4 436.6 455.5 19757.5 20588.5 21782.7 21893.2 21940.0 21743.9 23223.4 23542.1 23658.9 23769.8 6171.1 6450.1 6473.8 6482.3 6490.6 5174.3 6166.8 6299.3 6325.7 6373.9 5962.0 6267.7 6324.8 6334.4 6351.1 5299.3 6192.2 6252.6 6267.2 6278.6 4983.0 5463.3 5505.9 5571.2 5624.0 27098.7 27127.6 27626.4 27759.9 28677.7 2031.8 2036.5 2056.5 2061.0 2090.2 15389.7 16385.6 16556.5 16596.9 17239.8 1537.0 1578.1 1607.7 1615.9 1665.7 113191.9 138644.0 141864.9 145048.0 151956.0 10418.4 10781.6 11882.5 12628.6 12651.8 13405.5 15521.9 15933.6 16001.9 16328.4 7610.5 8081.6 8715.0 9242.4 9312.4 8869.4 8880.0 8880.4 9060.6 9063.6 7106.9 7503.8 7984.4 8119.0 8155.9 2200.0 2203.7 2206.5 2211.8 2384.2 856.2 922.3 946.6 985.8 999.0 21337.0 21514.6 21519.9 21580.5 21601.0 870.3 966.1 981.1 1008.8 1013.7 10153.1 10997.6 11118.4 11151.3 11184.4 583155.0 613237.0 657687.0 660896.0 685889.0 113719.0 141570.3 144306.0 156163.0 157177.0 1130.4 1150.8 1190.5 1229.8 1242.7 21325.0 21490.8 21728.9 21735.5 21739.8 63350.0 63449.0 64094.7 64641.7 64914.1 70738.9 91256.1 92474.0 92639.0 92733.1 8506.0 8608.1 8649.8 8769.0 8785.7 12521.2 13089.8 13498.7 13652.5 14090.3 11200.0 11334.1 11673.8 11812.4 12003.9 10803.7 10840.1 10952.9 11145.0 11242.9 11683.4 11971.8 11997.9 12014.6 12086.3 7166.0 8557.0 8606.8 8726.3 8897.8 11256.8 11591.8 11789.5 11904.5 11930.3 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:6810.23 Median:2497.03 stddev:4112.96 (164.7%) stddev-sans-first:4104.17 19:03:32 INFO - PID 1649 | Values: 1368.7 1405.7 1416.4 1421.8 1445.3 10672.2 10935.0 11107.9 11171.2 11291.8 11032.3 11185.6 11205.0 11206.1 11213.2 2458.6 2483.0 2492.2 2495.2 2498.8 2407.7 2443.3 2466.7 2468.4 2475.5 2426.6 2430.4 2451.1 2465.2 2479.1 2353.6 2359.0 2372.3 2372.5 2459.4 2353.5 2440.6 2444.5 2453.1 2460.5 9355.0 11042.8 11173.3 11226.2 11231.8 6279.3 6465.4 6506.7 6517.0 6524.3 3832.0 3988.7 4000.0 4046.9 4104.2 1149.9 1154.8 1181.7 1186.6 1203.9 13785.9 14159.0 15422.8 15489.0 15535.2 5311.9 5495.7 5509.9 5511.6 5523.4 11711.9 11736.9 11760.1 11784.4 11818.9 6132.3 6309.4 6310.4 6319.8 6329.4 5958.4 5997.3 5998.5 6071.5 6148.4 4321.7 4520.3 4587.2 4611.0 4668.4 1177.1 1429.7 1437.1 1442.2 1461.7 768.9 777.1 794.3 800.7 828.5 6248.1 6479.8 6736.9 6774.3 6873.4 848.3 886.8 894.7 919.6 964.6 3588.9 4048.7 4171.9 4219.9 4293.7 11294.9 13176.4 13419.5 13964.1 14038.2 10147.5 13637.0 13657.9 13708.3 13993.7 9941.8 10207.8 10239.6 10250.0 10276.9 5588.8 6321.7 6550.7 6834.4 6856.8 11645.6 12208.3 12338.0 12352.3 12489.0 11731.1 11741.5 12648.1 12681.4 12801.3 9086.0 9243.9 9350.6 9356.2 9369.6 9978.5 10224.6 10299.3 10317.6 10336.3 8598.3 8757.3 8837.6 8893.9 8952.0 8576.1 8614.7 8639.2 8639.8 8726.9 7802.3 7824.3 7896.0 7905.4 7936.9 5856.5 5876.9 5974.0 6041.3 6084.7 5786.9 5959.7 6058.2 6163.8 6167.4 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5592.52 Median:6284.59 stddev:1675.16 (26.7%) stddev-sans-first:1634.08 19:03:32 INFO - PID 1649 | Values: 410.3 410.8 413.1 414.4 419.2 6282.7 6353.5 6396.4 6451.3 6531.5 5116.5 6211.4 6328.2 6367.3 6375.7 4781.6 4860.7 4942.4 4952.4 5016.3 4113.2 4172.9 4899.8 5018.3 5026.9 4987.7 4996.0 5013.8 5020.0 5036.6 4270.7 4467.3 4874.5 4953.4 4993.1 4365.8 4370.9 4387.9 4391.1 4404.8 6035.6 6286.5 6292.0 6302.3 6309.4 5734.8 5804.9 5840.4 5843.9 5846.9 6920.0 6922.0 6929.5 6939.0 6939.7 4179.3 4735.4 4884.3 4939.7 4958.4 6889.7 7079.5 7170.4 7267.4 7361.7 4380.9 4838.7 4979.3 5010.9 5063.9 5088.7 5136.8 5145.3 5148.8 5155.7 4875.6 5561.1 5736.2 5739.3 5777.5 6722.9 6882.6 6897.1 6927.9 6936.8 1706.6 1744.7 1751.2 1755.7 1763.4 1003.3 1114.2 1122.9 1170.8 1200.3 5692.6 6425.8 6443.2 6462.3 6467.2 6404.7 6548.3 7095.7 7193.0 7194.6 4956.6 6117.8 6181.3 6303.2 6321.8 3642.2 3720.9 3724.7 3727.5 3823.8 6046.5 6677.8 6922.0 7250.8 7274.0 6182.1 6371.3 6908.5 7078.8 7274.3 5201.0 6321.3 6321.8 7156.1 7290.8 5678.2 6200.5 6332.5 6884.9 7202.5 5370.6 6271.8 7060.8 7081.6 7101.9 6777.4 7244.9 7332.3 7378.8 7385.1 4205.5 4871.4 5027.3 5047.6 5090.8 6987.7 7046.2 7123.2 7141.6 7164.4 6813.6 6854.7 6935.2 6935.5 6938.7 6398.9 6552.4 6753.6 6818.2 6891.9 6615.1 6642.7 6672.3 6744.4 6746.5 5649.4 6450.4 6822.8 6852.3 6898.1 6654.8 6657.9 6666.4 6675.4 6725.8 19:03:32 INFO - PID 1649 | 19:03:32 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:19135.19 Median:4008.78 stddev:27425.62 (684.1%) stddev-sans-first:27467.26 19:03:32 INFO - PID 1649 | Values: 605.0 681.2 698.7 725.9 731.0 14855.0 17278.7 18237.8 18341.4 18345.4 16303.9 17946.5 18072.9 18164.7 18204.8 839.3 944.3 969.8 971.7 973.2 834.4 923.4 940.4 963.4 964.1 991.9 995.1 995.2 995.2 1013.9 911.3 978.6 990.2 993.7 1013.6 729.6 751.9 762.6 764.2 764.7 13566.3 13673.9 13829.5 15011.9 16879.9 14703.4 14704.6 14814.8 14898.7 15121.5 8837.0 9672.7 11384.9 11817.5 11945.1 3051.3 3059.5 3071.3 3074.5 3179.6 81031.0 102664.0 106199.0 106703.5 109777.0 11302.9 11437.2 11550.3 11716.0 11836.5 23911.5 24310.9 24317.1 24488.5 24532.4 7787.6 7797.8 7848.8 7980.9 8020.2 3971.8 4045.8 4064.2 4067.8 4074.3 4049.0 4080.6 4123.2 4132.6 4138.8 739.9 782.9 782.9 795.8 811.0 542.5 575.2 580.4 582.4 583.0 9110.6 10019.0 10025.1 10054.3 10115.7 540.7 543.7 582.3 589.2 589.7 4205.8 4487.4 4554.8 4661.8 4670.6 103895.5 104720.0 106516.0 108068.5 110596.9 72616.6 73699.0 77300.6 89274.0 91382.5 66003.7 67228.7 67623.6 68171.3 68581.7 8012.2 10220.5 10593.4 10694.2 10740.5 36988.3 37635.9 37777.8 38046.2 38540.4 49643.0 49985.8 50231.7 50700.2 51321.0 11635.4 12409.6 12996.2 13211.1 13487.7 11255.4 11707.4 13982.8 14077.5 14314.9 10588.6 10715.9 12943.4 13127.3 13152.1 10185.4 12186.6 12504.4 12842.2 12991.2 12502.0 12748.6 12839.7 12988.9 13630.7 9167.0 9225.7 9316.8 9346.8 9860.4 9043.7 9113.0 10369.9 10535.5 10565.8 19:03:32 INFO - PID 1649 | -------- Summary: end -------- 19:03:32 INFO - PID 1649 | 19:03:33 INFO - TEST-INFO | 1649: exit 0 19:03:33 INFO - TEST-OK | dromaeo_css | took 1117997ms 19:03:33 INFO - TEST-START | kraken 19:03:33 INFO - operating with platform_type : mac_ 19:03:33 INFO - Initialising browser for kraken test... 19:03:33 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile http://localhost:49241/getInfo.html) 19:03:35 INFO - PID 1658 | 2017-03-27 19:03:35.955 plugin-container[1659:10328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 19:03:35 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:35 INFO - PID 1658 | 2017-03-27 19:03:35.958 plugin-container[1659:10328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 19:03:35 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:35 INFO - PID 1658 | 2017-03-27 19:03:35.958 plugin-container[1659:10328] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:36 INFO - PID 1658 | __metrics Screen width/height:1600/1200 19:03:36 INFO - PID 1658 | colorDepth:24 19:03:36 INFO - PID 1658 | Browser inner width/height: 1024/689 19:03:36 INFO - PID 1658 | __metrics 19:03:36 ERROR - PID 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 19:03:36 ERROR - PID 1658 | 1490666616114 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile/extensions/talos-powers@TalosPowersService.js:55:9 19:03:36 INFO - TEST-INFO | 1658: exit 0 19:03:36 INFO - Browser initialized. 19:03:36 INFO - Running cycle 1/1 for kraken test... 19:03:36 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpH077ET/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:39 INFO - PID 1660 | 2017-03-27 19:03:39.319 plugin-container[1661:10456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:03:39 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:39 INFO - PID 1660 | 2017-03-27 19:03:39.320 plugin-container[1661:10456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 19:03:39 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:39 INFO - PID 1660 | 2017-03-27 19:03:39.320 plugin-container[1661:10456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:48 INFO - PID 1660 | RSS: Main: 229011456 19:03:48 INFO - PID 1660 | 19:03:51 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 19:03:52 INFO - PID 1660 | RSS: Main: 243462144 19:03:52 INFO - PID 1660 | 19:03:55 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 19:03:56 INFO - PID 1660 | RSS: Main: 252518400 19:03:56 INFO - PID 1660 | 19:03:59 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 19:04:00 INFO - PID 1660 | RSS: Main: 254795776 19:04:00 INFO - PID 1660 | 19:04:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 19:04:02 INFO - PID 1660 | RSS: Main: 255991808 19:04:02 INFO - PID 1660 | 19:04:04 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:04:05 INFO - PID 1660 | RSS: Main: 256405504 19:04:05 INFO - PID 1660 | 19:04:08 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 19:04:09 INFO - PID 1660 | RSS: Main: 261513216 19:04:09 INFO - PID 1660 | 19:04:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 19:04:13 INFO - PID 1660 | RSS: Main: 262209536 19:04:13 INFO - PID 1660 | 19:04:16 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 19:04:17 INFO - PID 1660 | RSS: Main: 261849088 19:04:17 INFO - PID 1660 | 19:04:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:04:19 INFO - PID 1660 | RSS: Main: 261664768 19:04:19 INFO - PID 1660 | 19:04:21 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:04:21 INFO - PID 1660 | RSS: Main: 261636096 19:04:21 INFO - PID 1660 | 19:04:24 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:04:24 INFO - PID 1660 | RSS: Main: 263016448 19:04:24 INFO - PID 1660 | 19:04:27 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:04:28 INFO - PID 1660 | RSS: Main: 263393280 19:04:28 INFO - PID 1660 | 19:04:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:04:32 INFO - PID 1660 | RSS: Main: 271994880 19:04:32 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 19:04:34 INFO - PID 1660 | RSS: Main: 274452480 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | __start_tp_report 19:04:34 INFO - PID 1660 | _x_x_mozilla_page_load 19:04:34 INFO - PID 1660 | _x_x_mozilla_page_load_details 19:04:34 INFO - PID 1660 | |i|pagename|runs| 19:04:34 INFO - PID 1660 | |0;ai-astar;102;115;113;115;115;111;96;141;163;167 19:04:34 INFO - PID 1660 | |1;audio-beat-detection;146;114;99;189;148;148;116;114;132;114 19:04:34 INFO - PID 1660 | |2;audio-dft;198;205;202;218;225;236;231;181;282;286 19:04:34 INFO - PID 1660 | |3;audio-fft;69;60;67;118;48;77;85;88;95;59 19:04:34 INFO - PID 1660 | |4;audio-oscillator;87;71;85;74;70;73;83;71;72;83 19:04:34 INFO - PID 1660 | |5;imaging-gaussian-blur;90;90;84;134;108;90;93;89;90;89 19:04:34 INFO - PID 1660 | |6;imaging-darkroom;151;98;98;104;152;116;153;138;96;98 19:04:34 INFO - PID 1660 | |7;imaging-desaturate;123;111;79;150;149;150;91;92;93;136 19:04:34 INFO - PID 1660 | |8;json-parse-financial;66;67;66;67;67;67;68;67;65;67 19:04:34 INFO - PID 1660 | |9;json-stringify-tinderbox;57;71;54;69;62;62;56;56;56;57 19:04:34 INFO - PID 1660 | |10;stanford-crypto-aes;86;96;115;107;98;84;78;91;79;74 19:04:34 INFO - PID 1660 | |11;stanford-crypto-ccm;139;142;131;154;155;139;125;150;229;154 19:04:34 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;189;193;190;188;188;187;197;191;230;231 19:04:34 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;74;97;101;82;70;68;71;66;73;70 19:04:34 INFO - PID 1660 | __end_tp_report 19:04:34 INFO - PID 1660 | __start_cc_report 19:04:34 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1015 19:04:34 INFO - PID 1660 | __end_cc_report 19:04:34 INFO - PID 1660 | __startTimestamp1490666674445__endTimestamp 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | ------- Summary: start ------- 19:04:34 INFO - PID 1660 | Number of tests: 14 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:123.80 Median:128.00 stddev:24.63 (19.2%) stddev-sans-first:24.83 19:04:34 INFO - PID 1660 | Values: 102.0 115.0 113.0 115.0 115.0 111.0 96.0 141.0 163.0 167.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:147.00 stddev:26.40 (18.0%) stddev-sans-first:27.51 19:04:34 INFO - PID 1660 | Values: 146.0 114.0 99.0 189.0 148.0 148.0 116.0 114.0 132.0 114.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:226.40 Median:228.00 stddev:34.56 (15.2%) stddev-sans-first:35.10 19:04:34 INFO - PID 1660 | Values: 198.0 205.0 202.0 218.0 225.0 236.0 231.0 181.0 282.0 286.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:76.60 Median:73.00 stddev:20.57 (28.2%) stddev-sans-first:21.63 19:04:34 INFO - PID 1660 | Values: 69.0 60.0 67.0 118.0 48.0 77.0 85.0 88.0 95.0 59.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.50 stddev:6.72 (8.6%) stddev-sans-first:6.06 19:04:34 INFO - PID 1660 | Values: 87.0 71.0 85.0 74.0 70.0 73.0 83.0 71.0 72.0 83.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:90.00 stddev:14.84 (16.5%) stddev-sans-first:15.60 19:04:34 INFO - PID 1660 | Values: 90.0 90.0 84.0 134.0 108.0 90.0 93.0 89.0 90.0 89.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:120.40 Median:124.50 stddev:25.16 (20.2%) stddev-sans-first:24.12 19:04:34 INFO - PID 1660 | Values: 151.0 98.0 98.0 104.0 152.0 116.0 153.0 138.0 96.0 98.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:117.40 Median:114.50 stddev:27.80 (24.3%) stddev-sans-first:29.41 19:04:34 INFO - PID 1660 | Values: 123.0 111.0 79.0 150.0 149.0 150.0 91.0 92.0 93.0 136.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 19:04:34 INFO - PID 1660 | Values: 66.0 67.0 66.0 67.0 67.0 67.0 68.0 67.0 65.0 67.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.50 stddev:5.89 (9.9%) stddev-sans-first:6.14 19:04:34 INFO - PID 1660 | Values: 57.0 71.0 54.0 69.0 62.0 62.0 56.0 56.0 56.0 57.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:85.00 stddev:13.26 (15.6%) stddev-sans-first:13.95 19:04:34 INFO - PID 1660 | Values: 86.0 96.0 115.0 107.0 98.0 84.0 78.0 91.0 79.0 74.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:152.00 stddev:28.98 (19.1%) stddev-sans-first:30.36 19:04:34 INFO - PID 1660 | Values: 139.0 142.0 131.0 154.0 155.0 139.0 125.0 150.0 229.0 154.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.40 Median:192.00 stddev:17.17 (8.9%) stddev-sans-first:17.87 19:04:34 INFO - PID 1660 | Values: 189.0 193.0 190.0 188.0 188.0 187.0 197.0 191.0 230.0 231.0 19:04:34 INFO - PID 1660 | 19:04:34 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:72.00 stddev:12.30 (17.1%) stddev-sans-first:12.99 19:04:34 INFO - PID 1660 | Values: 74.0 97.0 101.0 82.0 70.0 68.0 71.0 66.0 73.0 70.0 19:04:34 INFO - PID 1660 | -------- Summary: end -------- 19:04:34 INFO - PID 1660 | 19:04:35 INFO - TEST-INFO | 1660: exit 0 19:04:35 INFO - TEST-OK | kraken | took 62070ms 19:04:35 INFO - SUITE-END | took 1180s 19:04:35 INFO - Completed test suite (00:19:40) 19:04:35 INFO - javascript benchmark 19:04:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7756.352102537685, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2865.4269146170764, 3580.91047723807, 3593.838277277522, 3629.981850090749, 3651.196736717922, 4947.529984651462, 6115.296740874801, 6122.2041134652545, 6233.844153896155, 6239.6880155992185, 6135.81592552223, 6143.0, 6147.108669242957, 6153.569250152491, 6208.255009398878, 4968.403791545002, 5589.832305030855, 5680.829575112753, 5682.318121825366, 5924.140999555084, 4897.510248975092, 5568.415316391782, 5696.946064977992, 5706.28671416073, 5865.266841644794, 5551.278333816578, 5586.273784408041, 5638.633488823213, 5752.165935939307, 5791.044477661202, 5605.383407825136, 5614.719264036782, 5721.713914304268, 5755.539556835443, 5764.538836893081, 5524.1989911463015, 5610.579206559483, 5612.017896868032, 5636.351819540723, 5636.379998200195, 5152.6650767699975, 6175.228096487939, 6241.407066328737, 6261.530385221081, 6266.87466250677, 6478.352164783531, 6576.6382848943285, 6637.502187335969, 6727.192736871607, 6745.561538500031, 5595.804146854881, 6787.55880867747, 6816.965915170442, 6828.180618325783, 6831.931680683179, 5759.020966435716, 6293.905591416133, 6300.432961033529, 6351.142595749561, 6363.363663633646, 5617.21359009739, 6596.80209593713, 6688.966555167291, 6790.388865002174, 6817.965910170417, 6411.4229719325485, 6542.967285163544, 6590.7693230736695, 6611.107500487469, 6652.334766523406, 5401.351837779492, 5419.783208671688, 6778.186617605918, 6825.1809782825385, 6861.691223894937, 6641.369069938348, 6699.665016749143, 6739.022841687928, 6740.4270636995425, 6758.155230596176, 5600.635958662674, 6463.6768161591735, 6576.473882089422, 6770.898436523456, 6779.457643388517, 5337.0, 5337.572994160458, 5363.329583802025, 5363.383210930715, 5372.704501252469, 4030.3349947258375, 4814.253790662453, 4880.292357608127, 4883.072216278895, 4897.902041959141, 6312.337204593543, 6339.746410143634, 6340.7463701451425, 6348.77779277723, 6374.298827129012, 6541.6075035498025, 6841.2132604750095, 6869.553479023847, 6869.931300687029, 6873.793786186422, 6212.316645169028, 6249.125122482856, 6304.085907543381, 6340.682965851689, 6363.745450182034, 6062.787802426894, 6095.573309868267, 6100.69496525172, 6105.049782388334, 6107.572469927151, 6871.9656401717675, 6948.6178260196175, 6959.130108736408, 6972.0, 6988.44092472601, 6547.770828020996, 6652.7006284716335, 6737.831554211184, 6810.216825064983, 6817.5568588041615, 6590.373914478117, 6885.862282754417, 6950.860982780215, 6959.26927672587, 6960.617166055916, 6689.163854518185, 6690.13028306317, 6709.329067093252, 6714.294999025227, 6734.292899245705, 6529.9020514691365, 6582.670866456562, 6669.09967154428, 6731.259561448139, 6829.317068293131, 5397.244385786072, 5870.324912635102, 6264.809686159615, 6598.27418983921, 6602.141721576164, 6191.597546159485, 6377.0753240778895, 6597.472202223715, 6608.603483790988, 6620.172478440195, 6365.0, 6405.679716014088, 6435.388638079375, 6441.484681225585, 6446.548741588043, 5493.862653433696, 6504.577202482013, 6599.670016499252, 6601.867962640817, 6607.669616519251, 6199.473044791243, 6432.774852880127, 6458.257300410513, 6459.063435801876, 6475.838104047437], "value": 6100.535531243692, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.9190527782077, 805.1063319715117, 827.9751607451776, 1170.1750266062427, 1273.624280837153, 31998.520022199686, 37606.0, 38685.613143868555, 38699.0, 38815.80592097039, 39378.21243575134, 39821.0, 39863.0, 39910.800445997804, 39950.00124996869, 7686.346660533862, 8212.055613604438, 8356.2897153742, 8577.27093197079, 8826.029136794947, 7774.727884524043, 8520.977482702085, 8592.097829727882, 8715.825683486326, 8718.34612404069, 8672.262857657106, 8705.042445331008, 8759.693410730626, 8851.424657397281, 8862.512561809099, 8763.255123314493, 8806.471611703319, 8820.823583528325, 8823.029466758651, 8824.0, 8634.956825215897, 8651.524166170859, 8657.22085012352, 8668.696595619185, 8689.391742578022, 31211.843940780378, 38659.42010869839, 38751.612483875084, 38800.80599596984, 38928.0, 2987.611610490644, 3019.773516986235, 3033.969660303391, 3034.9544756828664, 3047.801892876978, 28408.0, 28583.571246431322, 28694.85220591174, 28865.85567072151, 29032.983845565304, 2760.1029665358637, 2782.5949084855342, 2817.7886658500693, 2840.4887120318335, 2854.215090850012, 246888.0, 250988.0, 280383.59808201034, 281865.0, 283543.0, 47093.0, 47096.764516177886, 47352.5264747355, 47376.8155796101, 47478.76260618744, 48764.0, 50629.0, 51083.97832043413, 51257.71855703562, 51289.743551282, 12005.579804706793, 14659.633509162357, 14667.926660366844, 14678.0, 14696.265186740835, 11005.944970275257, 11020.622422197226, 11824.23142495735, 11983.28100313984, 11983.700407489709, 13834.23911684867, 13844.584662460142, 13853.722925541433, 13859.861401386059, 13963.441462341596, 5153.7165455900285, 6119.877602447926, 6124.785632502841, 6135.294441139237, 6149.600275982047, 855.3499340501264, 926.3006430145198, 948.8529277961927, 954.9904500954901, 996.4370130876032, 19275.22899084021, 23353.949072291787, 24575.87712061428, 24617.0, 24695.50608987846, 957.3346524165638, 1056.8837427882927, 1068.150820098024, 1073.1951036722458, 1089.7493576477455, 11942.522299107939, 12486.75026499465, 12500.312482813533, 12502.312372819582, 12519.937400313122, 480248.59875701094, 482369.0, 482408.0, 483419.0, 483611.0, 55614.0, 55989.72005139948, 56424.0, 56434.0, 56478.87042259132, 1493.746063169242, 1503.9699206015819, 1550.58909389012, 1557.1824791984072, 1565.053142848583, 21060.7363558187, 22647.508774034515, 23819.566142909258, 23946.7694273622, 24168.154114605903, 37051.62948370482, 39145.41281880831, 39389.01527461837, 39402.0, 39484.40773388459, 445332.0, 446741.0, 447266.0, 447919.0, 448295.0, 32629.34741305113, 33300.16749581183, 34437.0, 34510.0, 34536.82731586426, 41321.586784131774, 41327.79336103421, 41503.0, 41757.747267582025, 42035.36946945737, 27874.907903383053, 28970.42059158763, 29148.417031658824, 29757.256068598457, 30445.847770761004, 29537.2615684601, 29840.850795745882, 30336.0, 30343.696563034086, 30677.0, 33696.3260734779, 35541.46687799633, 36188.63811361958, 36312.4553131698, 36487.08782280464, 26219.606705899045, 27158.321041974108, 27818.304542386602, 30079.646415911775, 31087.2298389513, 22062.558748825253, 22247.332580022627, 22879.08483660635, 24091.75908240965, 24289.51420971606], "value": 17631.478878185346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.4319450407899, 419.230711644133, 431.37019950871735, 436.6070536517136, 455.52625269719493, 19757.506062348468, 20588.48528786781, 21782.673259901116, 21893.233736819213, 21940.0, 21743.91280435976, 23223.419414514647, 23542.058317667317, 23658.881705591448, 23769.76230237697, 6171.0743388491865, 6450.096986421881, 6473.802346565901, 6482.319356467574, 6490.643014634194, 5174.301469301652, 6166.7533298667995, 6299.275583307933, 6325.746970121189, 6373.94829853076, 5962.0, 6267.749290028393, 6324.841878953017, 6334.398232167937, 6351.079093531435, 5299.311089558352, 6192.225971753531, 6252.562320637558, 6267.185265915447, 6278.623282603058, 4983.0, 5463.31708536433, 5505.862353441196, 5571.164325351189, 5624.015797235467, 27098.729012709817, 27127.593086103527, 27626.447471050466, 27759.861200693867, 28677.713222867922, 2031.7765045844944, 2036.4806974221626, 2056.4550394145563, 2061.0210150178696, 2090.216168936649, 15389.692206155925, 16385.59036024097, 16556.50330490087, 16596.917015414965, 17239.82760172407, 1537.0009493828989, 1578.1041788318335, 1607.710612089835, 1615.935362585495, 1665.6668666266796, 113191.86808131896, 138644.0, 141864.87202691866, 145048.0, 151956.0, 10418.385150301716, 10781.568737250422, 11882.524699011943, 12628.558000469939, 12651.810222846665, 13405.463781448632, 15521.913466057493, 15933.601659958595, 16001.919990399974, 16328.428505002268, 7610.505317154367, 8081.6363263653275, 8714.9977752558, 9242.445453272825, 9312.394694344981, 8869.379143459895, 8879.955600222087, 8880.422772519765, 9060.637574496946, 9063.59213835379, 7106.862901935666, 7503.8499230015095, 7984.361251099898, 8118.959405202936, 8155.918440815634, 2199.988005517448, 2203.6584329428965, 2206.4925067234303, 2211.7677643847487, 2384.1536254629705, 856.2208390364738, 922.2621902478124, 946.6260826973307, 985.7683444390562, 998.9900100999042, 21337.0, 21514.56970860574, 21519.924003799748, 21580.46048848791, 21601.02795374212, 870.3037569944146, 966.1063516247443, 981.1071924548627, 1008.8083264179783, 1013.7110923386799, 10153.086222240034, 10997.560097596006, 11118.444077796077, 11151.330920144817, 11184.38485883268, 583155.0, 613237.0, 657687.0, 660896.0, 685889.0, 113719.0, 141570.29214854067, 144306.0, 156163.0, 157177.0, 1130.4404319861721, 1150.8388825564427, 1190.5470563724004, 1229.766344394562, 1242.6955395927876, 21325.0, 21490.78509214888, 21728.924219392335, 21735.456613584785, 21739.782602173775, 63350.0, 63449.0, 64094.67952660393, 64641.67679161574, 64914.05257842272, 70738.93891591733, 91256.08743912476, 92474.0, 92639.0, 92733.07266927244, 8506.023975974125, 8608.053114157563, 8649.783755405913, 8769.035406105195, 8785.692500762698, 12521.248725076524, 13089.803652945024, 13498.730025399633, 13652.522161724291, 14090.295485225493, 11200.047995920439, 11334.146390227033, 11673.766524669289, 11812.3503207321, 12003.939980300043, 10803.72990675208, 10840.078593319657, 10952.945235273774, 11144.996950274513, 11242.887571124511, 11683.357415342238, 11971.760564788481, 11997.880021199677, 12014.63956081319, 12086.274823510617, 7165.964170179116, 8557.015943166616, 8606.752020956801, 8726.345524085593, 8897.822043558963, 11256.83114753263, 11591.76816463683, 11789.528418863492, 11904.464299106388, 11930.343831089373], "value": 10401.233753432865, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.698886245026, 1405.7188562287538, 1416.3555582210083, 1421.848302874671, 1445.2990299704645, 10672.199585031125, 10935.0, 11107.944460277697, 11171.162162837789, 11291.830622540669, 11032.338059716423, 11185.608503702371, 11205.0, 11206.103511719064, 11213.215074944757, 2458.582041053021, 2483.0, 2492.239866840613, 2495.214007587613, 2498.837575557592, 2407.6629271901948, 2443.2792326263707, 2466.691663542057, 2468.4445999650115, 2475.504899020194, 2426.575349313872, 2430.404550885036, 2451.0685939343116, 2465.186488458813, 2479.1075212923333, 2353.6234202527685, 2359.044586942291, 2372.3001714494267, 2372.54921564903, 2459.446624509489, 2353.458704497958, 2440.6095024795945, 2444.4744379958393, 2453.0800949643885, 2460.4709987352803, 9354.953225233898, 11042.834357484644, 11173.329600224013, 11226.214165008452, 11231.775364492745, 6279.277883043463, 6465.418112369863, 6506.707198176094, 6517.0, 6524.282328943812, 3831.9616803832164, 3988.720789544733, 4000.0, 4046.898827529306, 4104.240715467648, 1149.9080073594093, 1154.809456439695, 1181.6809461445448, 1186.649938268213, 1203.8615559210627, 13785.931070344684, 14159.0, 15422.768658470131, 15489.0, 15535.223238838013, 5311.867203319871, 5495.670259784346, 5509.917351239735, 5511.559075273968, 5523.392426833045, 11711.88288117108, 11736.88263117375, 11760.117991150526, 11784.351860647746, 11818.881811181951, 6132.294786099566, 6309.400606942333, 6310.368963103745, 6319.8104056878365, 6329.367063293634, 5958.433948774859, 5997.340292567814, 5998.4601385875485, 6071.483923866433, 6148.354422785633, 4321.719088259253, 4520.299353600197, 4587.220172570671, 4610.95389046112, 4668.393108895822, 1177.1230433327055, 1429.6783223774567, 1437.1460815548035, 1442.185165381556, 1461.7222727681706, 768.8654485465021, 777.1296148313812, 794.3367288314207, 800.7117437722416, 828.4656396624143, 6248.125262463259, 6479.8056058318325, 6736.865262694817, 6774.254831968479, 6873.4157596603845, 848.2874385516072, 886.835935351962, 894.695803426838, 919.5769945824995, 964.6479035152119, 3588.8923332300074, 4048.716589838742, 4171.874843754692, 4219.936700949488, 4293.720908140961, 11294.887051129384, 13176.407061682246, 13419.463221471227, 13964.092333998258, 14038.227897465737, 10147.543360548645, 13637.0, 13657.931710341385, 13708.314584270747, 13993.72012559743, 9941.751456213653, 10207.846882296772, 10239.59041638341, 10250.0, 10276.948615256875, 5588.776448941997, 6321.652309123042, 6550.672466376567, 6834.419074378733, 6856.794296170923, 11645.59240426581, 12208.267503949792, 12337.951274141798, 12352.25886446816, 12489.000879929403, 11731.120165987755, 11741.471633776671, 12648.051396145142, 12681.429335679732, 12801.295928723637, 9086.0, 9243.907560924305, 9350.625974960925, 9356.204722598655, 9369.578368973276, 9978.501074946078, 10224.59101635956, 10299.330543514647, 10317.587296508056, 10336.276460647983, 8598.269147122564, 8757.255633270988, 8837.646494140163, 8893.866592001255, 8952.01527831951, 8576.142385761374, 8614.65541378338, 8639.179277968582, 8639.784005399915, 8726.912730872864, 7802.336801371947, 7824.334931530656, 7896.012998375203, 7905.407094467823, 7936.920630793618, 5856.502197313276, 5876.882462350814, 5974.0, 6041.308433638588, 6084.661374497603, 5786.884262314646, 5959.731812068554, 6058.151858739692, 6163.84590385244, 6167.352427994996], "value": 5234.223411002161, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.2779108768567, 410.767916127388, 413.13242191398047, 414.39912127415215, 419.22861934041356, 6282.68586570672, 6353.52348573857, 6396.360363963601, 6451.322611125829, 6531.477481801457, 5116.5139311765415, 6211.378862113786, 6328.208973878265, 6367.33143019982, 6375.713092910819, 4781.593564547018, 4860.684055536397, 4942.406911170665, 4952.430470495903, 5016.297718319438, 4113.177364527107, 4172.916541669165, 4899.8040078396825, 5018.272350509174, 5026.874328141789, 4987.650864439473, 4995.950040499603, 5013.774380152902, 5020.0, 5036.6222533309965, 4270.743755374688, 4467.3299005149165, 4874.512548745132, 4953.430355509108, 4993.051320249141, 4365.80353884076, 4370.934435983463, 4387.912241755179, 4391.121775644884, 4404.823807047715, 6035.577509574332, 6286.49708023357, 6291.968540157316, 6302.275238347557, 6309.432151106376, 5734.784933074187, 5804.8839023219725, 5840.3575606683235, 5843.912341314884, 5846.941530584682, 6919.95861220936, 6922.0, 6929.480288978297, 6939.0, 6939.722411103549, 4179.310413781723, 4735.408073990751, 4884.267359896008, 4939.678920870132, 4958.404991401055, 6889.655517224169, 7079.469039821983, 7170.3905168060255, 7267.382272506843, 7361.742339018162, 4380.9123817524105, 4838.7338696371335, 4979.278004689372, 5010.874728131826, 5063.949360506422, 5088.72012039334, 5136.7928536793825, 5145.331106956147, 5148.794048238028, 5155.690658560498, 4875.634327425457, 5561.138023606348, 5736.1682556029145, 5739.311282646109, 5777.508911742465, 6722.899156512656, 6882.5546635206165, 6897.137348338435, 6927.896081558781, 6936.826579335557, 1706.634692246198, 1744.7382892565963, 1751.2294590380193, 1755.683976884173, 1763.3916298876868, 1003.3277703938232, 1114.225613198819, 1122.854028976228, 1170.7658468306372, 1200.2978257719158, 5692.6299790513485, 6425.807225783234, 6443.162388889415, 6462.32145624712, 6467.159269294962, 6404.743810247538, 6548.345165483509, 7095.687297849811, 7192.985963930108, 7194.56108778236, 4956.553910148104, 6117.847053823601, 6181.289151747516, 6303.212098487689, 6321.841953951096, 3642.1987162824134, 3720.925581488355, 3724.7020238381406, 3727.4589140316807, 3823.847046118124, 6046.546509011841, 6677.799666010027, 6921.965390173017, 7250.818729531804, 7274.0, 6182.103594978703, 6371.267304259976, 6908.549204667076, 7078.75224367155, 7274.345308922222, 5200.97399513, 6321.336259692666, 6321.810345689545, 7156.141263048572, 7290.781276561712, 5678.2050512929045, 6200.534959878118, 6332.543514991486, 6884.862302753817, 7202.53183543068, 5370.624056316176, 6271.811845644638, 7060.788176354512, 7081.6459177039915, 7101.893471597827, 6777.356151165632, 7244.89132663, 7332.266773322625, 7378.778636640909, 7385.076865391826, 4205.474315710536, 4871.415430148263, 5027.321311622884, 5047.570956468741, 5090.847274581768, 6987.650617469005, 7046.189688185924, 7123.216446191019, 7141.607211603412, 7164.426845852215, 6813.591184528944, 6854.721864164665, 6935.167779866449, 6935.479839011993, 6938.687759050956, 6398.904016439664, 6552.443042341454, 6753.628550429577, 6818.18181818185, 6891.931080689129, 6615.140031796028, 6642.667866606554, 6672.266050734318, 6744.393004629607, 6746.470073722354, 5649.378568357395, 6450.41946224842, 6822.795316140524, 6852.349026842442, 6898.103246577913, 6654.803914708232, 6657.900131497934, 6666.400023997863, 6675.399214070757, 6725.764598239038], "value": 5074.1077804786055, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.9811440909859, 681.1689738519007, 698.7379732600278, 725.92377800331, 731.0423345417504, 14855.0, 17278.654426911453, 18237.81762182378, 18341.358052468167, 18345.35791247305, 16303.91848040761, 17946.461606151803, 18072.909635451804, 18164.727529087075, 18204.817951820478, 839.2866063845744, 944.2964991081636, 969.8351280282334, 971.7036303927299, 973.2360097323602, 834.3908946469081, 923.4367036107968, 940.3887473142444, 963.3834346018554, 964.0889359555223, 991.8660980767612, 995.0696099147284, 995.1938929467119, 995.2187532786763, 1013.8935411781767, 911.3028533172129, 978.5841017567541, 990.1633120013577, 993.6671215142936, 1013.6300250408584, 729.6388287797521, 751.8834580640009, 762.5653377574785, 764.228129589119, 764.7132325377984, 13566.253856037914, 13673.863261367458, 13829.51596694111, 15011.924940375338, 16879.915600422042, 14703.411863525445, 14704.632384190374, 14814.777778333333, 14898.702025959528, 15121.470748523749, 8837.002512725994, 9672.661456848977, 11384.943075284653, 11817.527298908033, 11945.104117191246, 3051.298201413665, 3059.495183294776, 3071.3089554850203, 3074.5234488654296, 3179.6343420506473, 81030.97422564424, 102664.0, 106199.0, 106703.46648266786, 109777.0, 11302.886971130183, 11437.199396042364, 11550.306981581132, 11716.0, 11836.48262329055, 23911.52176956451, 24310.878445607657, 24317.148899788423, 24488.510229795305, 24532.386690332885, 7787.5716835574585, 7797.844043119106, 7848.803779905548, 7980.920190798134, 8020.157883422274, 3971.7612359690256, 4045.7977101144825, 4064.248114098901, 4067.7559346439307, 4074.266632006267, 4049.0, 4080.5717998700216, 4123.2165888481095, 4132.607402296777, 4138.7997480730855, 739.8631253218172, 782.8943092682415, 782.9391174957964, 795.8328750962363, 810.9635066422026, 542.4873494547614, 575.2378099018816, 580.4050847880872, 582.4175824175824, 582.9533637309005, 9110.635574577076, 10019.0, 10025.14786243163, 10054.34646747959, 10115.696529104138, 540.6891037653287, 543.7308532276548, 582.3041465448748, 589.2251689028914, 589.7169358707758, 4205.75930100621, 4487.41663583732, 4554.817807287738, 4661.813527458931, 4670.556297151765, 103895.4805225969, 104720.0, 106516.0, 108068.45965770121, 110596.89403106026, 72616.63691681508, 73699.0, 77300.61349693328, 89274.0, 91382.54308728414, 66003.66998165075, 67228.6638566804, 67623.64752704918, 68171.3182868165, 68581.65709171422, 8012.214773111612, 10220.49777609519, 10593.417362045162, 10694.19793515505, 10740.462976851282, 36988.26023479569, 37635.870923872324, 37777.81111094427, 38046.239075218895, 38540.42189367105, 49643.01427942842, 49985.750356241384, 50231.748841257024, 50700.239496406844, 51321.0, 11635.360055197045, 12409.565665201675, 12996.220226786423, 13211.07522473456, 13487.662808429868, 11255.36797164423, 11707.356095414834, 13982.783959689124, 14077.542164607019, 14314.928425357806, 10588.629397971034, 10715.892841071489, 12943.417546210254, 13127.277999710108, 13152.145110567782, 10185.388876667424, 12186.634400967985, 12504.374781261082, 12842.165259258118, 12991.220526768046, 12501.981610653153, 12748.61754147377, 12839.679008024874, 12988.93505532504, 13630.727385452434, 9167.0, 9225.677101301691, 9316.767080823034, 9346.766330841783, 9860.359076659994, 9043.68347107848, 9112.95443522778, 10369.9481502592, 10535.473226338805, 10565.841512377474], "value": 7327.606620470814, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 115.0, 115.0, 111.0, 96.0, 141.0, 163.0, 167.0], "value": 123.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 99.0, 189.0, 148.0, 148.0, 116.0, 114.0, 132.0, 114.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 205.0, 202.0, 218.0, 225.0, 236.0, 231.0, 181.0, 282.0, 286.0], "value": 226.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 60.0, 67.0, 118.0, 48.0, 77.0, 85.0, 88.0, 95.0, 59.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 85.0, 74.0, 70.0, 73.0, 83.0, 71.0, 72.0, 83.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 84.0, 134.0, 108.0, 90.0, 93.0, 89.0, 90.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 98.0, 98.0, 104.0, 152.0, 116.0, 153.0, 138.0, 96.0, 98.0], "value": 120.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 111.0, 79.0, 150.0, 149.0, 150.0, 91.0, 92.0, 93.0, 136.0], "value": 117.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0, 65.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 71.0, 54.0, 69.0, 62.0, 62.0, 56.0, 56.0, 56.0, 57.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 96.0, 115.0, 107.0, 98.0, 84.0, 78.0, 91.0, 79.0, 74.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 131.0, 154.0, 155.0, 139.0, 125.0, 150.0, 229.0, 154.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 193.0, 190.0, 188.0, 188.0, 187.0, 197.0, 191.0, 230.0, 231.0], "value": 198.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 97.0, 101.0, 82.0, 70.0, 68.0, 71.0, 66.0, 73.0, 70.0], "value": 77.2, "unit": "score"}]}]} 19:04:35 INFO - Return code: 0 19:04:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:04:35 ERROR - # TBPL SUCCESS # 19:04:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:04:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:04:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:04:35 INFO - 2017-03-27 19:04:35.767 screenresolution[1665:10860] starting screenresolution argv=screenresolution get 19:04:35 INFO - 2017-03-27 19:04:35.770 screenresolution[1665:10860] Display 0: 1600x1200x32@60 19:04:35 INFO - 2017-03-27 19:04:35.776 screenresolution[1666:10863] starting screenresolution argv=screenresolution list 19:04:35 INFO - Available Modes on Display 0 19:04:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:04:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:04:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:04:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:04:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:04:36 INFO - Intel Iris: 19:04:36 INFO - Chipset Model: Intel Iris 19:04:36 INFO - Type: GPU 19:04:36 INFO - Bus: Built-In 19:04:36 INFO - VRAM (Dynamic, Max): 1536 MB 19:04:36 INFO - Vendor: Intel (0x8086) 19:04:36 INFO - Device ID: 0x0a2e 19:04:36 INFO - Revision ID: 0x0009 19:04:36 INFO - Displays: 19:04:36 INFO - Display: 19:04:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:04:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:04:36 INFO - Display Serial Number: 1600x1200 60 19:04:36 INFO - Main Display: Yes 19:04:36 INFO - Mirror: Off 19:04:36 INFO - Online: Yes 19:04:36 INFO - Rotation: Supported 19:04:36 INFO - Return code: 0 19:04:36 INFO - Running post-action listener: _resource_record_post_action 19:04:36 INFO - [mozharness: 2017-03-28 02:04:36.010731Z] Finished run-tests step (success) 19:04:36 INFO - Running post-run listener: _resource_record_post_run 19:04:36 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39508480; Write bytes: 517393408; Read time: 269; Write time: 1632 19:04:36 INFO - TinderboxPrint: CPU usage
54.2% 19:04:36 INFO - TinderboxPrint: I/O read bytes / time
39,508,480 / 269 19:04:36 INFO - TinderboxPrint: I/O write bytes / time
517,393,408 / 1,632 19:04:36 INFO - TinderboxPrint: CPU idle
2,201.9 (45.8%) 19:04:36 INFO - TinderboxPrint: CPU system
969.4 (20.2%) 19:04:36 INFO - TinderboxPrint: CPU user
1,634.5 (34.0%) 19:04:36 INFO - TinderboxPrint: Swap in / out
250,372,096 / 0 19:04:36 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203364864; Write bytes: 202571776; Read time: 15703; Write time: 430 19:04:36 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 38349312; Write bytes: 304851968; Read time: 252; Write time: 1168 19:04:36 INFO - Running post-run listener: _upload_blobber_files 19:04:36 INFO - Blob upload gear active. 19:04:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:04:36 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 19:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:04:36 INFO - (blobuploader) - INFO - Open directory for files ... 19:04:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:04:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:04:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:38 INFO - (blobuploader) - INFO - Done attempting. 19:04:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:04:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:04:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:39 INFO - (blobuploader) - INFO - Done attempting. 19:04:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:04:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:04:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:40 INFO - (blobuploader) - INFO - Done attempting. 19:04:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:04:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:04:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:44 INFO - (blobuploader) - INFO - Done attempting. 19:04:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:04:44 INFO - Return code: 0 19:04:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:04:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:04:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db638d1b2c4221532d55b38633d635a9adebeb7202a35843e5562ee0f6ac3a22fab20d10560b1beb31939f4ad5faa85afac09e50dab95cf1b6622aebf0c4063a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cf6c7668981a8feb4b9a9ec2fa5e9c53a9f8b741795fc2a647035575347ff08d26c2e3fef43d1855a679f686c0b26712f5e3745cb6538293bbdf1f6a3c0ad431", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca3ce59aa50a9a1c61154df24aa411788fcc599e8235fddc021e999524d8a10dc2279c73c87a64b4d373f049fba99c02211db16306df1eb2f3f8c42688e3cf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0fd1fe75fbd635ad0e6395f50c3ec87ff0b25b3f6ca099fcc9800a9d2c1aaaf5f7d288e921a97a81fdea45b2876856a2e0f7b96fb552f7d6898bd3ada231fa3e"} 19:04:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:04:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:04:44 INFO - Contents: 19:04:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db638d1b2c4221532d55b38633d635a9adebeb7202a35843e5562ee0f6ac3a22fab20d10560b1beb31939f4ad5faa85afac09e50dab95cf1b6622aebf0c4063a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cf6c7668981a8feb4b9a9ec2fa5e9c53a9f8b741795fc2a647035575347ff08d26c2e3fef43d1855a679f686c0b26712f5e3745cb6538293bbdf1f6a3c0ad431", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca3ce59aa50a9a1c61154df24aa411788fcc599e8235fddc021e999524d8a10dc2279c73c87a64b4d373f049fba99c02211db16306df1eb2f3f8c42688e3cf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0fd1fe75fbd635ad0e6395f50c3ec87ff0b25b3f6ca099fcc9800a9d2c1aaaf5f7d288e921a97a81fdea45b2876856a2e0f7b96fb552f7d6898bd3ada231fa3e"} 19:04:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:04:44 INFO - Copying logs to upload dir... 19:04:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:04:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.506935 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-27 19:04:44.475134) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 19:04:44.478500) ========= 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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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/Pine/sha512/db638d1b2c4221532d55b38633d635a9adebeb7202a35843e5562ee0f6ac3a22fab20d10560b1beb31939f4ad5faa85afac09e50dab95cf1b6622aebf0c4063a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cf6c7668981a8feb4b9a9ec2fa5e9c53a9f8b741795fc2a647035575347ff08d26c2e3fef43d1855a679f686c0b26712f5e3745cb6538293bbdf1f6a3c0ad431", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca3ce59aa50a9a1c61154df24aa411788fcc599e8235fddc021e999524d8a10dc2279c73c87a64b4d373f049fba99c02211db16306df1eb2f3f8c42688e3cf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0fd1fe75fbd635ad0e6395f50c3ec87ff0b25b3f6ca099fcc9800a9d2c1aaaf5f7d288e921a97a81fdea45b2876856a2e0f7b96fb552f7d6898bd3ada231fa3e"} build_url:https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/dTTPdutOSnKC1olAYKNMIQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/db638d1b2c4221532d55b38633d635a9adebeb7202a35843e5562ee0f6ac3a22fab20d10560b1beb31939f4ad5faa85afac09e50dab95cf1b6622aebf0c4063a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cf6c7668981a8feb4b9a9ec2fa5e9c53a9f8b741795fc2a647035575347ff08d26c2e3fef43d1855a679f686c0b26712f5e3745cb6538293bbdf1f6a3c0ad431", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ca3ce59aa50a9a1c61154df24aa411788fcc599e8235fddc021e999524d8a10dc2279c73c87a64b4d373f049fba99c02211db16306df1eb2f3f8c42688e3cf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0fd1fe75fbd635ad0e6395f50c3ec87ff0b25b3f6ca099fcc9800a9d2c1aaaf5f7d288e921a97a81fdea45b2876856a2e0f7b96fb552f7d6898bd3ada231fa3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 19:04:44.512290) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 19:04:44.512632) ========= 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.w4NiV71Ea6/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.nz3rxZa4t5/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.004985 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 19:04:44.560979) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 19:04:44.561315) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 19:04:44.561644) ========= ========= Total master_lag: 0.18 =========