builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0335 starttime: 1491077364.27 results: success (0) buildid: 20170401122822 builduid: 2dd696fc99a14e7185094bb151fab019 revision: a3d322392d3ff48865a98dbac7f0dd8de2d51d9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.270177) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.270725) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.271082) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.296754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.297073) ========= 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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-01 13:09:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.4M=0.001s 2017-04-01 13:09:24 (10.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.494802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.815349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.815693) ========= 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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.025650 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:24.898609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-01 13:09:24.899020) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a3d322392d3ff48865a98dbac7f0dd8de2d51d9f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a3d322392d3ff48865a98dbac7f0dd8de2d51d9f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-01 13:09:25,021 truncating revision to first 12 chars 2017-04-01 13:09:25,021 Setting DEBUG logging. 2017-04-01 13:09:25,021 attempt 1/10 2017-04-01 13:09:25,021 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a3d322392d3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-01 13:09:26,116 unpacking tar archive at: mozilla-aurora-a3d322392d3f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.372598 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-01 13:09:26.300602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:26.301009) ========= script_repo_revision: a3d322392d3ff48865a98dbac7f0dd8de2d51d9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:26.301497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:26.301840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-01 13:09:26.361505) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-01 13:09:26.361978) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:09:26 INFO - MultiFileLogger online at 20170401 13:09:26 in /builds/slave/test 13:09:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:09:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:09:26 INFO - {'append_to_log': False, 13:09:26 INFO - 'base_work_dir': '/builds/slave/test', 13:09:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:09:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:09:26 INFO - 'branch': 'Mozilla-Aurora', 13:09:26 INFO - 'buildbot_json_path': 'buildprops.json', 13:09:26 INFO - 'config_files': ('talos/mac_config.py',), 13:09:26 INFO - 'default_actions': ('clobber', 13:09:26 INFO - 'read-buildbot-config', 13:09:26 INFO - 'download-and-extract', 13:09:26 INFO - 'populate-webroot', 13:09:26 INFO - 'create-virtualenv', 13:09:26 INFO - 'install', 13:09:26 INFO - 'run-tests'), 13:09:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:09:26 INFO - 'download_minidump_stackwalk': True, 13:09:26 INFO - 'download_symbols': 'ondemand', 13:09:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:09:26 INFO - 'tooltool.py': '/tools/tooltool.py', 13:09:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:09:26 INFO - '/tools/misc-python/virtualenv.py')}, 13:09:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:09:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:09:26 INFO - 'gecko_profile': False, 13:09:26 INFO - 'gecko_profile_interval': 0, 13:09:26 INFO - 'installer_path': 'installer.exe', 13:09:26 INFO - 'log_level': 'info', 13:09:26 INFO - 'log_name': 'talos', 13:09:26 INFO - 'log_to_console': True, 13:09:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:09:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:09:26 INFO - 'opt_config_files': (), 13:09:26 INFO - 'pip_index': False, 13:09:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:26 INFO - 'cmd': ('bash', 13:09:26 INFO - '-c', 13:09:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:26 INFO - 'enabled': True, 13:09:26 INFO - 'halt_on_failure': False, 13:09:26 INFO - 'name': 'check_screen_resolution'},), 13:09:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:26 INFO - 'cmd': ('bash', 13:09:26 INFO - '-c', 13:09:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:26 INFO - 'enabled': True, 13:09:26 INFO - 'halt_on_failure': False, 13:09:26 INFO - 'name': 'check_screen_resolution'},), 13:09:26 INFO - 'run_cmd_checks_enabled': True, 13:09:26 INFO - 'suite': 'dromaeojs-e10s', 13:09:26 INFO - 'system_bits': '32', 13:09:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:09:26 INFO - 'title': 't-yosemite-r7-0335', 13:09:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:09:26 INFO - 'use_talos_json': True, 13:09:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:09:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:09:26 INFO - 'work_dir': 'build'} 13:09:26 INFO - [mozharness: 2017-04-01 20:09:26.613947Z] Running clobber step. 13:09:26 INFO - Running pre-action listener: _resource_record_pre_action 13:09:26 INFO - Running main action method: clobber 13:09:26 INFO - rmtree: /builds/slave/test/build 13:09:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:09:30 INFO - Running post-action listener: _resource_record_post_action 13:09:30 INFO - [mozharness: 2017-04-01 20:09:30.311818Z] Finished clobber step (success) 13:09:30 INFO - [mozharness: 2017-04-01 20:09:30.312027Z] Running read-buildbot-config step. 13:09:30 INFO - Running pre-action listener: _resource_record_pre_action 13:09:30 INFO - Running main action method: read_buildbot_config 13:09:30 INFO - Using buildbot properties: 13:09:30 INFO - { 13:09:30 INFO - "project": "", 13:09:30 INFO - "product": "firefox", 13:09:30 INFO - "script_repo_revision": "production", 13:09:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:09:30 INFO - "repository": "", 13:09:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:09:30 INFO - "buildid": "20170401122822", 13:09:30 INFO - "pgo_build": "False", 13:09:30 INFO - "basedir": "/builds/slave/test", 13:09:30 INFO - "buildnumber": 4, 13:09:30 INFO - "slavename": "t-yosemite-r7-0335", 13:09:30 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 13:09:30 INFO - "platform": "yosemite_r7", 13:09:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:09:30 INFO - "branch": "mozilla-aurora", 13:09:30 INFO - "repo_path": "releases/mozilla-aurora", 13:09:30 INFO - "revision": "a3d322392d3ff48865a98dbac7f0dd8de2d51d9f", 13:09:30 INFO - "stage_platform": "macosx64", 13:09:30 INFO - "builduid": "2dd696fc99a14e7185094bb151fab019", 13:09:30 INFO - "slavebuilddir": "test" 13:09:30 INFO - } 13:09:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 13:09:30 INFO - Running post-action listener: _resource_record_post_action 13:09:30 INFO - [mozharness: 2017-04-01 20:09:30.317655Z] Finished read-buildbot-config step (success) 13:09:30 INFO - [mozharness: 2017-04-01 20:09:30.317856Z] Running download-and-extract step. 13:09:30 INFO - Running pre-action listener: _resource_record_pre_action 13:09:30 INFO - Running main action method: download_and_extract 13:09:30 INFO - mkdir: /builds/slave/test/build/tests 13:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:09:30 INFO - trying https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 13:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 13:09:31 INFO - Downloaded 1199 bytes. 13:09:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:09:31 INFO - Using the following test package requirements: 13:09:31 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:09:31 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 13:09:31 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 13:09:31 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'jsshell-mac.zip'], 13:09:31 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 13:09:31 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:09:31 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 13:09:31 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 13:09:31 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 13:09:31 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:09:31 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 13:09:31 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:09:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:09:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:09:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:09:33 INFO - Expected file size: 51792711 13:09:33 INFO - Obtained file size: 51792711 13:09:49 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:09:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:09:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:09:49 INFO - Fetch https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:09:51 INFO - Expected file size: 51792711 13:09:51 INFO - Obtained file size: 51792711 13:10:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 13:10:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:10:05 INFO - Fetch https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 13:10:06 INFO - Expected file size: 14404438 13:10:06 INFO - Obtained file size: 14404438 13:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:07 INFO - trying https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:10:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 13:10:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 13:10:09 INFO - Downloaded 74684640 bytes. 13:10:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:10:09 INFO - mkdir: /builds/slave/test/properties 13:10:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:10:09 INFO - Writing to file /builds/slave/test/properties/build_url 13:10:09 INFO - Contents: 13:10:09 INFO - build_url:https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:10:09 INFO - Running post-action listener: _resource_record_post_action 13:10:09 INFO - Running post-action listener: set_extra_try_arguments 13:10:09 INFO - [mozharness: 2017-04-01 20:10:09.964121Z] Finished download-and-extract step (success) 13:10:09 INFO - [mozharness: 2017-04-01 20:10:09.964264Z] Running populate-webroot step. 13:10:09 INFO - Running pre-action listener: _resource_record_pre_action 13:10:09 INFO - Running main action method: populate_webroot 13:10:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:10:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:10:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:10:09 INFO - '--tppagecycles', 13:10:09 INFO - '7'], 13:10:09 INFO - 'tests': ['tsvgm']}}, 13:10:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:10:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:10:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:10:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:10:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:10:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:10:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['damp', 'tps']}, 13:10:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'tests': ['damp', 'tps']}, 13:10:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['dromaeo_dom']}, 13:10:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:10:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['basic_compositor_video']}, 13:10:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:10:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['a11yr', 13:10:09 INFO - 'ts_paint', 13:10:09 INFO - 'tpaint', 13:10:09 INFO - 'sessionrestore', 13:10:09 INFO - 'sessionrestore_no_auto_restore', 13:10:09 INFO - 'tabpaint']}, 13:10:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['tsvgx', 13:10:09 INFO - 'tsvgr_opacity', 13:10:09 INFO - 'tart', 13:10:09 INFO - 'tscrollx', 13:10:09 INFO - 'cart', 13:10:09 INFO - 'tsvg_static']}, 13:10:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:10:09 INFO - 'tsvgr_opacity', 13:10:09 INFO - 'tart', 13:10:09 INFO - 'tscrollx', 13:10:09 INFO - 'cart', 13:10:09 INFO - 'tsvg_static']}, 13:10:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'talos_options': ['--disable-e10s'], 13:10:09 INFO - 'tests': ['tp5o']}, 13:10:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:10:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'talos_options': ['--disable-e10s', 13:10:09 INFO - '--xperf_path', 13:10:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:10:09 INFO - 'tests': ['tp5n']}, 13:10:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:10:09 INFO - 'talos_options': ['--xperf_path', 13:10:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:10:09 INFO - 'tests': ['tp5n']}}, 13:10:09 INFO - 'talos.zip': {'path': '', 13:10:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:10:09 INFO - Running post-action listener: _resource_record_post_action 13:10:09 INFO - [mozharness: 2017-04-01 20:10:09.982882Z] Finished populate-webroot step (success) 13:10:09 INFO - [mozharness: 2017-04-01 20:10:09.983020Z] Running create-virtualenv step. 13:10:09 INFO - Running pre-action listener: _resource_record_pre_action 13:10:09 INFO - Running main action method: create_virtualenv 13:10:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:10:09 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 13:10:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:10:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:10:10 INFO - Using real prefix '/tools/python27' 13:10:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:10:10 INFO - Installing distribute.............................................................................................................................................................................................done. 13:10:13 INFO - Installing pip.................done. 13:10:13 INFO - Return code: 0 13:10:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:10:13 INFO - Reading from file tmpfile_stdout 13:10:13 INFO - Output received: 13:10:13 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) 13:10:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:13 INFO - 'HOME': '/Users/cltbld', 13:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:13 INFO - 'LOGNAME': 'cltbld', 13:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:13 INFO - 'MOZ_NO_REMOTE': '1', 13:10:13 INFO - 'NO_EM_RESTART': '1', 13:10:13 INFO - 'PAGER': '/bin/cat', 13:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:13 INFO - 'PWD': '/builds/slave/test', 13:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:13 INFO - 'SHELL': '/bin/bash', 13:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:13 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:13 INFO - 'USER': 'cltbld', 13:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:13 INFO - 'XPC_FLAGS': '0x0', 13:10:13 INFO - 'XPC_SERVICE_NAME': '0', 13:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:14 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 13:10:14 INFO - Cleaning up... 13:10:14 INFO - Return code: 0 13:10:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:10: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')]} 13:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10: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')]} 13:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10: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', '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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:10: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 psutil>=3.1.1 13:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:14 INFO - 'HOME': '/Users/cltbld', 13:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:14 INFO - 'LOGNAME': 'cltbld', 13:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:14 INFO - 'MOZ_NO_REMOTE': '1', 13:10:14 INFO - 'NO_EM_RESTART': '1', 13:10:14 INFO - 'PAGER': '/bin/cat', 13:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:14 INFO - 'PWD': '/builds/slave/test', 13:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:14 INFO - 'SHELL': '/bin/bash', 13:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:14 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:14 INFO - 'USER': 'cltbld', 13:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:14 INFO - 'XPC_FLAGS': '0x0', 13:10:14 INFO - 'XPC_SERVICE_NAME': '0', 13:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:14 INFO - Downloading/unpacking psutil>=3.1.1 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:10:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:10:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:10:18 INFO - Installing collected packages: psutil 13:10:18 INFO - Running setup.py install for psutil 13:10:18 INFO - building 'psutil._psutil_osx' extension 13:10:18 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 13:10:18 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 13:10:19 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 13:10:19 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 13:10:19 INFO - building 'psutil._psutil_posix' extension 13:10:19 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 13:10:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:10:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:10:19 INFO - ^ 13:10:19 INFO - 1 warning generated. 13:10:19 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 13:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:10:19 INFO - Successfully installed psutil 13:10:19 INFO - Cleaning up... 13:10:19 INFO - Return code: 0 13:10:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:10: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')]} 13:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10: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')]} 13:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:19 INFO - 'HOME': '/Users/cltbld', 13:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:19 INFO - 'LOGNAME': 'cltbld', 13:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:19 INFO - 'MOZ_NO_REMOTE': '1', 13:10:19 INFO - 'NO_EM_RESTART': '1', 13:10:19 INFO - 'PAGER': '/bin/cat', 13:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:19 INFO - 'PWD': '/builds/slave/test', 13:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:19 INFO - 'SHELL': '/bin/bash', 13:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:19 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:19 INFO - 'USER': 'cltbld', 13:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:19 INFO - 'XPC_FLAGS': '0x0', 13:10:19 INFO - 'XPC_SERVICE_NAME': '0', 13:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:10:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:10:22 INFO - Installing collected packages: mozsystemmonitor 13:10:22 INFO - Running setup.py install for mozsystemmonitor 13:10:22 INFO - Successfully installed mozsystemmonitor 13:10:22 INFO - Cleaning up... 13:10:22 INFO - Return code: 0 13:10:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:22 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:22 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 13:10:22 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 13:10:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:22 INFO - 'HOME': '/Users/cltbld', 13:10:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:22 INFO - 'LOGNAME': 'cltbld', 13:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:22 INFO - 'MOZ_NO_REMOTE': '1', 13:10:22 INFO - 'NO_EM_RESTART': '1', 13:10:22 INFO - 'PAGER': '/bin/cat', 13:10:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:22 INFO - 'PWD': '/builds/slave/test', 13:10:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:22 INFO - 'SHELL': '/bin/bash', 13:10:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:22 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:22 INFO - 'USER': 'cltbld', 13:10:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:22 INFO - 'XPC_FLAGS': '0x0', 13:10:22 INFO - 'XPC_SERVICE_NAME': '0', 13:10:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:22 INFO - Downloading/unpacking jsonschema==2.5.1 13:10:22 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 13:10:22 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 13:10:22 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 13:10:22 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 13:10:22 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 13:10:22 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 13:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:10:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:10:25 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 13:10:25 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 13:10:25 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 13:10:25 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 13:10:25 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 13:10:25 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 13:10:25 INFO - Downloading functools32-3.2.3-2.tar.gz 13:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:10:26 INFO - warning: no files found matching '*.txt' 13:10:26 INFO - no previously-included directories found matching 'build' 13:10:26 INFO - no previously-included directories found matching 'dist' 13:10:26 INFO - no previously-included directories found matching '.git*' 13:10:26 INFO - Installing collected packages: jsonschema, functools32 13:10:26 INFO - Running setup.py install for functools32 13:10:26 INFO - warning: no files found matching '*.txt' 13:10:26 INFO - no previously-included directories found matching 'build' 13:10:26 INFO - no previously-included directories found matching 'dist' 13:10:26 INFO - no previously-included directories found matching '.git*' 13:10:26 INFO - Successfully installed jsonschema functools32 13:10:26 INFO - Cleaning up... 13:10:26 INFO - Return code: 0 13:10:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:10:26 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')]} 13:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:26 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')]} 13:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:26 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:26 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 13:10:26 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 13:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:26 INFO - 'HOME': '/Users/cltbld', 13:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:26 INFO - 'LOGNAME': 'cltbld', 13:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:26 INFO - 'MOZ_NO_REMOTE': '1', 13:10:26 INFO - 'NO_EM_RESTART': '1', 13:10:26 INFO - 'PAGER': '/bin/cat', 13:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:26 INFO - 'PWD': '/builds/slave/test', 13:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:26 INFO - 'SHELL': '/bin/bash', 13:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:26 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:26 INFO - 'USER': 'cltbld', 13:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:26 INFO - 'XPC_FLAGS': '0x0', 13:10:26 INFO - 'XPC_SERVICE_NAME': '0', 13:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:10:26 INFO - Cleaning up... 13:10:26 INFO - Return code: 0 13:10:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:10:26 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')]} 13:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:26 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')]} 13:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:26 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:26 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 13:10:26 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 13:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:26 INFO - 'HOME': '/Users/cltbld', 13:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:26 INFO - 'LOGNAME': 'cltbld', 13:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:26 INFO - 'MOZ_NO_REMOTE': '1', 13:10:26 INFO - 'NO_EM_RESTART': '1', 13:10:26 INFO - 'PAGER': '/bin/cat', 13:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:26 INFO - 'PWD': '/builds/slave/test', 13:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:26 INFO - 'SHELL': '/bin/bash', 13:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:26 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:26 INFO - 'USER': 'cltbld', 13:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:26 INFO - 'XPC_FLAGS': '0x0', 13:10:26 INFO - 'XPC_SERVICE_NAME': '0', 13:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:26 INFO - Downloading/unpacking blobuploader==1.2.4 13:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:29 INFO - Downloading blobuploader-1.2.4.tar.gz 13:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:10:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:10:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:10:30 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 13:10:30 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 13:10:30 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 13:10:30 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 13:10:30 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 13:10:30 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 13:10:30 INFO - Downloading docopt-0.6.1.tar.gz 13:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:10:30 INFO - Installing collected packages: blobuploader, requests, docopt 13:10:30 INFO - Running setup.py install for blobuploader 13:10:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:10:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:10:31 INFO - Running setup.py install for requests 13:10:31 INFO - Running setup.py install for docopt 13:10:31 INFO - Successfully installed blobuploader requests docopt 13:10:31 INFO - Cleaning up... 13:10:31 INFO - Return code: 0 13:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:10:31 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')]} 13:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:31 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')]} 13:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:31 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:31 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 13:10:31 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 13:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:31 INFO - 'HOME': '/Users/cltbld', 13:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:31 INFO - 'LOGNAME': 'cltbld', 13:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:31 INFO - 'MOZ_NO_REMOTE': '1', 13:10:31 INFO - 'NO_EM_RESTART': '1', 13:10:31 INFO - 'PAGER': '/bin/cat', 13:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:31 INFO - 'PWD': '/builds/slave/test', 13:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:31 INFO - 'SHELL': '/bin/bash', 13:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:31 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:31 INFO - 'USER': 'cltbld', 13:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:31 INFO - 'XPC_FLAGS': '0x0', 13:10:31 INFO - 'XPC_SERVICE_NAME': '0', 13:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-wHJvqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-EBlJZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-TfLnOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-GWrWeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-gCY1oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-_JAm3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-RFdvwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-aDasgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:10:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-C8sGvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-bRDZ03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-L55Z1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3mxH3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-hYvHze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-JttoM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-PjkI1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-0Cc2Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:10:33 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-rayeP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:10:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:10:33 INFO - Running setup.py install for manifestparser 13:10:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:10:34 INFO - Running setup.py install for mozcrash 13:10:34 INFO - Running setup.py install for mozdebug 13:10:34 INFO - Running setup.py install for mozdevice 13:10:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:10:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:10:34 INFO - Running setup.py install for mozfile 13:10:34 INFO - Running setup.py install for mozhttpd 13:10:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:10:34 INFO - Running setup.py install for mozinfo 13:10:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:10:34 INFO - Running setup.py install for mozInstall 13:10:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Running setup.py install for mozleak 13:10:35 INFO - Running setup.py install for mozlog 13:10:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Running setup.py install for moznetwork 13:10:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Running setup.py install for mozprocess 13:10:35 INFO - Running setup.py install for mozprofile 13:10:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:10:35 INFO - Running setup.py install for mozrunner 13:10:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:10:36 INFO - Running setup.py install for mozscreenshot 13:10:36 INFO - Running setup.py install for moztest 13:10:36 INFO - Running setup.py install for mozversion 13:10:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:10:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:10:36 INFO - Cleaning up... 13:10:36 INFO - Return code: 0 13:10:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:10:36 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')]} 13:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:36 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')]} 13:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:36 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:36 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 13:10:36 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 13:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:36 INFO - 'HOME': '/Users/cltbld', 13:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:36 INFO - 'LOGNAME': 'cltbld', 13:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:36 INFO - 'MOZ_NO_REMOTE': '1', 13:10:36 INFO - 'NO_EM_RESTART': '1', 13:10:36 INFO - 'PAGER': '/bin/cat', 13:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:36 INFO - 'PWD': '/builds/slave/test', 13:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:36 INFO - 'SHELL': '/bin/bash', 13:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:36 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:36 INFO - 'USER': 'cltbld', 13:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:36 INFO - 'XPC_FLAGS': '0x0', 13:10:36 INFO - 'XPC_SERVICE_NAME': '0', 13:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:10:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-IPQ41Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:10:36 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)) 13:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:10:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-GQgsFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-qL5JyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-n_XuG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-lAuJPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3JREaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Iw80S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-iB6rlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-m_jiUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-8BULdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Kl4xSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:10:37 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)) 13:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:10:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Iyb_rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:10:38 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)) 13:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:10:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-InjdvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:10:38 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)) 13:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:10:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-uHy6D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:10:38 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)) 13:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:10:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-SkWDBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:10:38 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)) 13:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:10:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-A0SwYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:10:38 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)) 13:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:10:38 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-xcH5Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:10:38 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)) 13:10:38 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)) 13:10:38 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)) 13:10:38 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)) 13:10:38 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)) 13:10:38 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)) 13:10:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:41 INFO - Downloading blessings-1.6.tar.gz 13:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:10:41 INFO - Installing collected packages: blessings 13:10:41 INFO - Running setup.py install for blessings 13:10:41 INFO - Successfully installed blessings 13:10:41 INFO - Cleaning up... 13:10:41 INFO - Return code: 0 13:10:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:10:41 INFO - Reading from file tmpfile_stdout 13:10:41 INFO - Current package versions: 13:10:41 INFO - blessings == 1.6 13:10:41 INFO - blobuploader == 1.2.4 13:10:41 INFO - docopt == 0.6.1 13:10:41 INFO - functools32 == 3.2.3-2 13:10:41 INFO - jsonschema == 2.5.1 13:10:41 INFO - manifestparser == 1.1 13:10:41 INFO - mozInstall == 1.12 13:10:41 INFO - mozcrash == 1.0 13:10:41 INFO - mozdebug == 0.1 13:10:41 INFO - mozdevice == 0.49 13:10:41 INFO - mozfile == 1.2 13:10:41 INFO - mozhttpd == 0.7 13:10:41 INFO - mozinfo == 0.9 13:10:41 INFO - mozleak == 0.1 13:10:41 INFO - mozlog == 3.4 13:10:41 INFO - moznetwork == 0.27 13:10:41 INFO - mozprocess == 0.25 13:10:41 INFO - mozprofile == 0.28 13:10:41 INFO - mozrunner == 6.13 13:10:41 INFO - mozscreenshot == 0.1 13:10:41 INFO - mozsystemmonitor == 0.3 13:10:41 INFO - moztest == 0.8 13:10:41 INFO - mozversion == 1.4 13:10:41 INFO - psutil == 3.1.1 13:10:41 INFO - requests == 1.2.3 13:10:41 INFO - wsgiref == 0.1.2 13:10:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:41 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:41 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 13:10:41 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 13:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:41 INFO - 'HOME': '/Users/cltbld', 13:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:41 INFO - 'LOGNAME': 'cltbld', 13:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:41 INFO - 'MOZ_NO_REMOTE': '1', 13:10:41 INFO - 'NO_EM_RESTART': '1', 13:10:41 INFO - 'PAGER': '/bin/cat', 13:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:41 INFO - 'PWD': '/builds/slave/test', 13:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:41 INFO - 'SHELL': '/bin/bash', 13:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:41 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:41 INFO - 'USER': 'cltbld', 13:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:41 INFO - 'XPC_FLAGS': '0x0', 13:10:41 INFO - 'XPC_SERVICE_NAME': '0', 13:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 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)) 13:10:42 INFO - Cleaning up... 13:10:42 INFO - Return code: 0 13:10:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:10:42 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')]} 13:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:42 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')]} 13:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:42 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 0x1075edb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075f0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90b9c9f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, '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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 13:10:42 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 13:10:42 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 13:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:10:42 INFO - 'HOME': '/Users/cltbld', 13:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:10:42 INFO - 'LOGNAME': 'cltbld', 13:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:42 INFO - 'MOZ_NO_REMOTE': '1', 13:10:42 INFO - 'NO_EM_RESTART': '1', 13:10:42 INFO - 'PAGER': '/bin/cat', 13:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:10:42 INFO - 'PWD': '/builds/slave/test', 13:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:10:42 INFO - 'SHELL': '/bin/bash', 13:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:10:42 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:10:42 INFO - 'USER': 'cltbld', 13:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:42 INFO - 'XPC_FLAGS': '0x0', 13:10:42 INFO - 'XPC_SERVICE_NAME': '0', 13:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:10:42 INFO - Cleaning up... 13:10:42 INFO - Return code: 0 13:10:42 INFO - Running post-action listener: _resource_record_post_action 13:10:42 INFO - Running post-action listener: _start_resource_monitoring 13:10:42 INFO - Starting resource monitoring. 13:10:42 INFO - [mozharness: 2017-04-01 20:10:42.460098Z] Finished create-virtualenv step (success) 13:10:42 INFO - [mozharness: 2017-04-01 20:10:42.461079Z] Running install step. 13:10:42 INFO - Running pre-action listener: _resource_record_pre_action 13:10:42 INFO - Running main action method: install 13:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:10:42 INFO - Reading from file tmpfile_stdout 13:10:42 INFO - Detecting whether we're running mozinstall >=1.0... 13:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:10:42 INFO - Reading from file tmpfile_stdout 13:10:42 INFO - Output received: 13:10:42 INFO - Usage: mozinstall [options] installer 13:10:42 INFO - Options: 13:10:42 INFO - -h, --help show this help message and exit 13:10:42 INFO - -d DEST, --destination=DEST 13:10:42 INFO - Directory to install application into. [default: 13:10:42 INFO - "/builds/slave/test"] 13:10:42 INFO - --app=APP Application being installed. [default: firefox] 13:10:42 INFO - mkdir: /builds/slave/test/build/application 13:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:11:03 INFO - Reading from file tmpfile_stdout 13:11:03 INFO - Output received: 13:11:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:11:03 INFO - Running post-action listener: _resource_record_post_action 13:11:03 INFO - [mozharness: 2017-04-01 20:11:03.136177Z] Finished install step (success) 13:11:03 INFO - [mozharness: 2017-04-01 20:11:03.136336Z] Running run-tests step. 13:11:03 INFO - Running pre-action listener: _resource_record_pre_action 13:11:03 INFO - Running main action method: run_tests 13:11:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:11:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:11:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:11:03 INFO - 2017-04-01 13:11:03.151 screenresolution[6904:136143] starting screenresolution argv=screenresolution get 13:11:03 INFO - 2017-04-01 13:11:03.154 screenresolution[6904:136143] Display 0: 1600x1200x32@60 13:11:03 INFO - 2017-04-01 13:11:03.159 screenresolution[6905:136145] starting screenresolution argv=screenresolution list 13:11:03 INFO - Available Modes on Display 0 13:11:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:11:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:11:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:11:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:11:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:11:03 INFO - Intel Iris: 13:11:03 INFO - Chipset Model: Intel Iris 13:11:03 INFO - Type: GPU 13:11:03 INFO - Bus: Built-In 13:11:03 INFO - VRAM (Dynamic, Max): 1536 MB 13:11:03 INFO - Vendor: Intel (0x8086) 13:11:03 INFO - Device ID: 0x0a2e 13:11:03 INFO - Revision ID: 0x0009 13:11:03 INFO - Displays: 13:11:03 INFO - Display: 13:11:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:11:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:11:03 INFO - Display Serial Number: 1600x1200 60 13:11:03 INFO - Main Display: Yes 13:11:03 INFO - Mirror: Off 13:11:03 INFO - Online: Yes 13:11:03 INFO - Rotation: Supported 13:11:03 INFO - Return code: 0 13:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:11:03 INFO - Python 2.7.3 13:11:03 INFO - Return code: 0 13:11:03 INFO - grabbing minidump binary from tooltool 13:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:03 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 0x1073cbd30>, '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 0x1072f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073c0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:11:03 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 13:11:03 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 13:11:03 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 13:11:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:11:03 INFO - Return code: 0 13:11:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:11:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:11:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:11:03 INFO - ENV: RUST_BACKTRACE is now 1 13:11:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:11:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:11:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 13:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --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 13:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAdp8l8vWN/Render', 13:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:03 INFO - 'HOME': '/Users/cltbld', 13:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:03 INFO - 'LOGNAME': 'cltbld', 13:11:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:11:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:03 INFO - 'MOZ_NO_REMOTE': '1', 13:11:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:11:03 INFO - 'NO_EM_RESTART': '1', 13:11:03 INFO - 'PAGER': '/bin/cat', 13:11:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:03 INFO - 'PWD': '/builds/slave/test', 13:11:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:03 INFO - 'RUST_BACKTRACE': '1', 13:11:03 INFO - 'SHELL': '/bin/bash', 13:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwXFA2FSlN/Listeners', 13:11:03 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 13:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:03 INFO - 'USER': 'cltbld', 13:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:03 INFO - 'XPC_FLAGS': '0x0', 13:11:03 INFO - 'XPC_SERVICE_NAME': '0', 13:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 13:11:03 INFO - mozversion application_buildid: 20170401122822 13:11:03 INFO - mozversion application_changeset: a3d322392d3ff48865a98dbac7f0dd8de2d51d9f 13:11:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:11:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:11:03 INFO - mozversion application_name: Firefox 13:11:03 INFO - mozversion application_remotingname: firefox-dev 13:11:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:11:03 INFO - mozversion application_vendor: Mozilla 13:11:03 INFO - mozversion application_version: 54.0a2 13:11:03 INFO - mozversion platform_buildid: 20170401122822 13:11:03 INFO - mozversion platform_changeset: a3d322392d3ff48865a98dbac7f0dd8de2d51d9f 13:11:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:11:03 INFO - mozversion platform_version: 54.0a2 13:11:03 INFO - using testdate: 1491077463 13:11:03 INFO - actual date: 1491077463 13:11:03 INFO - starting webserver on 'localhost:49665' 13:11:03 INFO - SUITE-START | Running 2 tests 13:11:03 INFO - TEST-START | dromaeo_css 13:11:03 INFO - operating with platform_type : mac_ 13:11:03 INFO - Initialising browser for dromaeo_css test... 13:11:03 INFO - TEST-INFO | started process 6915 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpkEkZJL/profile http://localhost:49665/getInfo.html) 13:11:06 INFO - PROCESS | 6915 | 2017-04-01 13:11:06.647 plugin-container[6917:136306] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 13:11:06 INFO - PROCESS | 6915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:11:06 INFO - PROCESS | 6915 | 2017-04-01 13:11:06.651 plugin-container[6917:136306] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 13:11:06 INFO - PROCESS | 6915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:11:06 INFO - PROCESS | 6915 | 2017-04-01 13:11:06.651 plugin-container[6917:136306] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:11:06 INFO - PROCESS | 6915 | __metrics Screen width/height:1600/1200 13:11:06 INFO - PROCESS | 6915 | colorDepth:24 13:11:06 INFO - PROCESS | 6915 | Browser inner width/height: 1024/689 13:11:06 INFO - PROCESS | 6915 | __metrics 13:11:06 ERROR - PROCESS | 6915 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:11:07 INFO - TEST-INFO | 6915: exit 0 13:11:07 INFO - Browser initialized. 13:11:07 INFO - Running cycle 1/1 for dromaeo_css test... 13:11:07 INFO - TEST-INFO | started process 6918 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpkEkZJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:11:10 INFO - PROCESS | 6918 | 2017-04-01 13:11:10.015 plugin-container[6919:136439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 13:11:10 INFO - PROCESS | 6918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:11:10 INFO - PROCESS | 6918 | 2017-04-01 13:11:10.017 plugin-container[6919:136439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 13:11:10 INFO - PROCESS | 6918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:11:10 INFO - PROCESS | 6918 | 2017-04-01 13:11:10.017 plugin-container[6919:136439] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:11:19 INFO - PROCESS | 6918 | RSS: Main: 220753920 13:11:19 INFO - PROCESS | 6918 | 13:14:23 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49665/tests/dromaeo/cssquery-ext.html) 13:14:25 INFO - PROCESS | 6918 | RSS: Main: 239001600 13:14:25 INFO - PROCESS | 6918 | 13:16:08 INFO - PROCESS | 6918 | ************************* 13:16:08 INFO - PROCESS | 6918 | A coding exception was thrown and uncaught in a Task. 13:16:08 INFO - PROCESS | 6918 | 13:16:08 ERROR - PROCESS | 6918 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:16:08 INFO - PROCESS | 6918 | Full stack: 13:16:08 INFO - PROCESS | 6918 | ************************* 13:17:27 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-ext.html (next: http://localhost:49665/tests/dromaeo/cssquery-jquery.html) 13:17:27 INFO - PROCESS | 6918 | RSS: Main: 249810944 13:17:27 INFO - PROCESS | 6918 | 13:20:29 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49665/tests/dromaeo/cssquery-mootools.html) 13:20:30 INFO - PROCESS | 6918 | RSS: Main: 256241664 13:20:30 INFO - PROCESS | 6918 | 13:23:32 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49665/tests/dromaeo/cssquery-prototype.html) 13:23:33 INFO - PROCESS | 6918 | RSS: Main: 252395520 13:23:33 INFO - PROCESS | 6918 | 13:26:36 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49665/tests/dromaeo/cssquery-yui.html) 13:26:36 INFO - PROCESS | 6918 | RSS: Main: 257589248 13:26:36 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | Cycle 1(1): loaded http://localhost:49665/tests/dromaeo/cssquery-yui.html (next: http://localhost:49665/tests/dromaeo/cssquery-dojo.html) 13:29:39 INFO - PROCESS | 6918 | RSS: Main: 253681664 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | __start_tp_report 13:29:39 INFO - PROCESS | 6918 | _x_x_mozilla_page_load 13:29:39 INFO - PROCESS | 6918 | _x_x_mozilla_page_load_details 13:29:39 INFO - PROCESS | 6918 | |i|pagename|runs| 13:29:39 INFO - PROCESS | 6918 | |0;dojo.html;2952.54235593483;3417.8461969211367;3441.4665726812354;3458.7751796133266;3474.9922522468487;5555.027870122727;5582.358028826686;6146.81559553213;6148.692565371724;6159.476444502211;6081.695915204245;6116.6024208426425;6132.080187971796;6152.077188421739;6164.075388691698;5441.83674489766;5484.232207490954;5507.173923911425;5511.366192887829;5562.471565201319;4592.97703511482;5555.777768889239;5569.972150139243;5580.358258800229;5591.580631452637;4466.352378905057;5335.279737235463;5487.807926722566;5573.386927437979;5591.664500129985;4760.666753327268;5169.526684894787;5599.272094627713;5602.60781745278;5637.830865074055;5435.1847222916485;5499.092649712793;5505.779768809243;5507.394186639466;5527.7560204148;5753.367129615739;5873.941260587425;6008.549358798107;6011.1283863839935;6185.845353866144;6310.495160387158;6348.746050157988;6365.809025729235;6424.678766061678;6428.967855160741;6281.775053864499;6587.176602924635;6589.637569933651;6592.17597800275;6592.208934927791;6258.15514905491;6280.15217945576;6290.716917738713;6300.810975670737;6318.74725010999;6232.501399887998;6371.33101024395;6466.418022377915;6613.933860661428;6619.371159739817;5169.896602068013;6327.050942358683;6395.360463953567;6404.391582799627;6431.5819471734185;5789.681567513743;6526.379993900572;6537.771178008747;6545.476361891037;6572.34276572339;6257.561970662101;6279.995200767855;6311.96844015777;6327.3988971047675;6342.968285158545;5313.814016509404;6145.985912324416;6420.711068001951;6504.38208370204;6518.152640156749;5189.481051894781;5190.117679994334;5218.112920803473;5253.947460525423;5291.656042357235;4263.531011588722;4630.768461576908;4641.6518761092375;4652.441706995182;4672.229082201467;5871;5936.198613187251;5937.732802023833;5946.07835785454;6016.067509536029;5383.111786555253;6488.221413430419;6492.35076492347;6537.771178008747;6551.1155993941165;5189.273501709764;5415.756290966916;6177.351378105234;6183.412575805291;6192.256929168439;5567.498925096761;5605.887882242332;5640.8871822563315;5791.247137872049;5833.270841144857;6566.376194261537;6636.303188165173;6651.7006734698025;6655.966720166368;6657.966710166418;5812.796552120655;6464.095026696173;6566.704498297492;6573.934260657524;6584.868302634016;6373.171487706569;6713.160854893138;6751.324867513209;6764.019217213378;6794.286599906938;6302.5588208826775;6324.019776934582;6383.840403989938;6502.187226596676;6503.122078519528;6564.737410503526;6567.573107747981;6567.9014814776865;6571.244306904767;6586.012098185311;6339.112524246517;6348.936510634834;6383.87232255343;6388.840278993062;6410.8076757697345;6176.629402235881;6227.034809604518;6266.122742816101;6273.843153921189;6352.523560733056;5421.725894414888;6044.90932636002;6226.315105338319;6338.651374174465;6368.331325210971;6243.562950593415;6252.937470625236;6307.810765677037;6333.936660633335;6354.777582784581;6196.1015652731;6258.655773932477;6276.592021518587;6290.654014029273;6312.810615681537 13:29:39 INFO - PROCESS | 6918 | |1;ext.html;698.5773606967784;729.7847135095146;771.479251505234;793.7817100297419;812.9593520323986;38047;38842.02894927628;38868;38870;38946.221075578505;39815.60184398155;40020.39969400461;40067.599324006755;40142;40177.7991110044;9213.631454741819;9342.532873356338;9380.624775008991;10345.741356466073;10608.045275925166;10416.166706663449;10445.84331235032;10453.581856725721;10521.579136834518;10523.105536029447;9008.369414141012;9266.629334826632;10521.894781052168;10544.841827372557;10563.471826408688;10456.634017809416;10490.842637360445;10509.84235236472;10528.578856845794;10532.631357902514;10316.948415257952;10327.948360258151;10397.79204415915;10403.323783954018;10405.063544280976;32781.836090819634;32917;33005;33136.50295245573;33203.83398083018;3661.9267614647556;3742.288965096623;3744.1762812181278;3763.849446022156;3783.205526839367;24328.39179020521;24464.755352446427;24971.87514062418;25806.87096564524;25952.961881524716;2974.7917645764805;3042.9847850760825;3100.4884194107944;3149.1497295730023;3250.236194494292;332654;348800.7679884804;349202;349559;350880;42532.78733606312;42930;42940;43121.137577248905;43271.56728432675;44767.55232447634;44807.10385792266;44875.87810304768;44921.775391122836;44968.77515612466;13662.18026918388;13673.86326136726;13796.03427760047;13821.170729756246;13841.584752457442;11765.941170294094;11769.411529423494;11906.642800715992;11941.044716422666;11980.281183128865;10040.39757614531;11604.187706860439;11665.650030499099;11681.941590292165;11758.941205293919;4824.044283687343;5323.893522129536;5490.051031883703;5543.223948647193;5561.304836895388;1009.1674368645896;1063.8510608514814;1067.2102644043507;1090.4547726136932;1120.4117838135044;22560.88719556392;25344.98620055177;25710.485790284463;26426.810793514338;26546.601800973;1197.8261303922031;1223.8041913294007;1260.8802163794426;1277.8530468996184;1283.6919139406475;11762.94118529419;13514.256715880525;13614.523491677943;13660.65848353799;13817.792733109012;477658;477835;479453;481609;482166;56879.715601422555;57087.14369284464;57109.42890571041;57306.426935730946;57706.42293577094;1381.530279704885;1395.818543589327;1419.6876687128815;1430.8712215900412;1434.5624584501827;25599;26341.868290658425;26587.60118598184;26760.063397781763;26855.86572067127;39325.60674393219;39452.605473944895;39456.80271598624;39471.605283947945;39596.010099746585;473436;482467;483547;484706;484872;27672.861635691694;28037.878484860375;28120.57819132674;28144.01495947714;28154.577681334387;36416.28308687712;36515.63484365122;36518.90443286706;36964.63035369612;37343;25009.749902500742;25944.221673349828;26490.470190596465;26683;26792;26241.737582624693;26344.604830927936;26684.466310674066;26980.32549186286;27159;30291.394172115994;31107.533386999665;31301.904433344724;31402.900898468444;31486;20844.791552084283;23626.054957801494;24557.649329287062;26512.602310964965;27020.594691079255;20627.793722062586;22087.1145187534;22102.77897221007;22146.889265553567;22162.556748865256 13:29:39 INFO - PROCESS | 6918 | |2;jquery.html;397.7037107355021;403.7395879657619;404.7814180342616;417.92686279901017;437.73517022201565;19633.312834050812;20451.48871278219;20624;20746.481337966557;20956.476088097806;13938.51215207468;15346.010037390275;15355.385784568603;16225.594360141002;21491.46271343217;6377.234731832175;6658.067870498134;6948.652567371611;7306.123265208183;7369.520981136236;6737.494687898403;7039.768040592901;7158.427325813923;7291.963540182292;7322.597257150855;7372.778816635509;7426.220246874082;7427.9257207428045;7436.033315668956;7473.252674732537;6619.205695316593;6946.548474349152;7101;7320.158181809107;7391.704331826721;6943.79168624942;7127.501074924758;7134.357907788324;7156.355927966508;7166.784996450115;21596.352109436742;22866.42833929165;24284.514309713708;24439;24710.629340559728;2895.6959519250595;2991.9551206731917;3112.3308488675043;3120.7815452918303;3276.950845737316;14612.123272603678;15272.930894837365;15293.388264469519;15606.921965390215;15724.899257052011;2067.8345732341377;2071.78246284141;2077.3352527191305;2104.305579158889;2126.362091372582;138519;138634;141294.29352853273;143902.99999999895;145209.27395362954;15808.52574422769;18951.86288822675;19113;19738.70391944122;19814.108365123604;26745;27136.457270854866;27222.72777272202;27256.45487090247;27325.180244592695;9920.355176913477;9921.50392480373;9925.503724813874;9946;10236.385816851012;7211.459140564479;8496.745097647081;8612.397132200686;8652.697155599524;8816.51509167002;8712.823768791131;8789.340799440068;8850.822983540293;8871.467711937175;8887.73336799897;3628.909277268089;4084.244414783275;4123.454452124755;4155.10665206982;4157.91684166315;814.5764202614725;915.1260546178383;1008.8234558952154;1031.7626945802508;1072.0030371754187;19545.902270488557;20616.484587885123;20926.581468370547;21079.156833726785;21188;944.6646440513656;1051.400701600096;1072.099436473366;1083.2092572422175;1102.9503672334765;8902.332325075646;10194.604267997014;10811.027007569357;10814.94592527048;10854.511546980404;771608;778672;783358;783984;792239;123690;154816;156296.21851890668;159549;161465.19267403588;1034.6068493972243;1111.9666410007712;1223.5350566784564;1268.733565951142;1270.1680399338447;17817;18852.3401680946;19058.142383592494;19135.90432047831;19142.8085719141;58441;62172.68913655403;62312.37687623247;63684.68157659368;66734;89862.10137898538;90506.54746726224;91021;91531;91825.08174918166;11241.156913231376;11385.772284554429;11476;11488.19582629208;11730.530778769094;12599.748005039664;12881.35593220354;12956.805647915477;12982.415791289226;13027;10553.102986245947;10815.134789216687;10873.118623564604;10948.934139357518;11016.394098324668;10131.13885319756;10326.225533084898;10371.94814025925;10625.233808015377;10725.517351719036;8893.599788009426;9193.632254709737;11040.944795275973;11108.055815255793;11369.31784092924;11264.718382040186;11265.436728163722;11618.244814087057;11688.70778230551;11727.941360293486;10700.892991070301;10778.407187604756;10792.56829726802;10928;11121.666350009513 13:29:39 INFO - PROCESS | 6918 | |3;mootools.html;1505.9247037648115;1537.2621141851903;1560.5162399656108;1566.8824838137143;1577.8501042400972;9412.81174376512;9613.42319460833;10626.415547144903;11127;11146.888531114686;9282.767930801734;10511.947440262808;10857.891421085787;10871.83692244617;10883.619073332435;2365.787079162875;2396.2451827674226;2416.4804567018086;2416.903323867043;2425.599776036952;2366.751491093436;2373.2761507740133;2397.280815755275;2413.9517209655796;2415.405832150781;2361.7638236176417;2380.6310021946624;2387.6776635154292;2394.4373072328076;2397.0411835265945;2017.5460521382645;2337.555864385761;2349.765023497637;2366.0181024874655;2374.715034195899;2358.3632419246705;2364.6571247169063;2374.8693821839793;2381.1546900850294;2385.8210634202505;11149.554017839277;11203.551857925673;11241.887581124165;11365.204435689506;11392.829107563393;5740.13897915316;5836.182934389189;5854.560907931879;5932.644041357532;5938.881222375571;3166.904992850218;3488.8081155536433;3598.1904071583945;3624.9818750906074;3657.7622454540638;1158.8435561199215;1158.8493495845487;1189.6668932698858;1210.074293165729;1211.2066596379386;13652.385642646104;15007.32467038975;15539.223038848126;15601.063936163753;15618.531444056696;4674.271670875548;4779.5220477951925;4807.711537307773;4847.345608342828;4875.7074575525585;12705.936470317589;12740.93629531865;12743.235405875677;12743.235405875677;12745.235285882876;4931.3835770528685;5836.737346819404;5853.619514731528;5912.704364781744;5926.762929482772;4442.5557444255965;4883.975580122148;5214.921776173361;5267.26258323835;5356.544693701001;3931.4299426583557;4021.2560676274984;4084.7753373564738;4092.8362865485046;4160;940.5438362394233;1039.7419122861422;1044.3629386074488;1117.2737720481753;1130.8077626803463;810.5420437452821;942.6088173408097;950.8098380323963;964.6382606522554;969.8642190093393;6472.579282346726;6563.671816409161;6590.110335104795;6746.123004009447;6827.419669328064;882.3999680217514;993.8956409577034;1002.3634991780272;1010.7372083258406;1057.603398725478;4242.681798865097;4252.4471818664;4255.893602659897;4262.850800221977;4396.296592545177;11620.477078531487;13565.932170339085;13593.660158496117;13619.114757540729;13664.93167534156;11133;13430.54375214322;13549.322533873266;13549.45802167922;13588;10137.949310253549;10196.3372380795;10198.694039178688;10222.846657300146;10234.02776736209;6532.346765323429;6552.443042341454;6625.734970601122;6629.867402651824;6877.346652068046;11753.588624398106;12091.093168012258;12126.818097728717;12216.755664886474;12233.816492752438;12209.877901220874;12420.441080151595;12497.437615307515;12501.124921255425;12584.937075314565;8116.188381161836;8254.958725206336;9007.09929007094;9077.273818094405;9323.300752443458;10521.316114452537;10544.89455105439;10601.151907847508;10617.362958222531;10627.36235825853;9139;9213.861792073258;9216.493092879957;9305.581248843953;9373.578188981648;8875.778105547413;9157.77105572366;9184.311176661644;9234.1689247968;9339.252859771337;7880.881786773089;7990.280874721303;8038.196180382149;8088.352931765562;8089.2315230053055;5881.470667639933;5945.197398351167;6003.7898673546215;6104.145419641165;6191.752329906753;5972.701364931823;6095.725692343766;6105.145279660762;6224.906626400517;6254.249490061222 13:29:39 INFO - PROCESS | 6918 | |4;prototype.html;316.59792064078607;362.34958249941354;364.52247555702013;383.80042377963457;384.72684394080204;5111.15998240634;5219.791208351666;5247.323864828936;5313.973430132849;5445.537129344006;5139.897202055957;5181.274621552986;5271.789128434858;5273.523413444233;5314;4199.202151591203;4239.8092085856215;4568.885777855556;4581.908361832761;4585.426821647294;4136.648384887289;4325.913481730364;4500.923752933546;4617.792199351021;4630.7265501986885;3873.198962482445;4213.768242746648;4525.298578720303;4551.928872363083;4600.194965880974;4008.83964641414;4288.935665965013;4542.704724192933;4546.318052292149;4626.352310676508;3438.484227365915;4376.971411718244;4378.430803995492;4382.649388048949;4389.209942210401;4317.589828966243;4793.452844894788;4917.60659147271;5153.587712982952;5202.205239192473;4620;4634.837780677693;5079.492050794928;5086.745662716887;5096.311997880276;4410.889727756799;5538.972305138448;5693.146028095819;5715.3998830122655;5770.653760774367;3676.7242456815848;4078.8980275493054;4306.246406878815;4344.717593356421;4499.257622492285;5511.858240802744;5679.438154507502;5797.37663960025;5819;5874.765009399619;4081.5306239782212;4361.815874411697;4571;4581.977090114528;4585.9770701146945;4146.854860079883;4464.441944756905;4660.487346391894;4669.2996050592;4702.811887524529;4091.4067460218102;4364.738115713068;4731.597814185764;4978.5270399312;5016.573591244785;4836.613070954315;5204;5363.946360536344;5436.026036176933;5572.554195664418;1482.8887833412537;1557.509384543865;1569.6344180562983;1592.7258193445198;1605.5825485373887;1089.226649079147;1159.5535718748174;1177.7055736065984;1199.7540504196618;1201.3961361582385;4442.400275962768;4675.779004972679;4909.789116767292;4995.0259699359;5159.329287192641;4804.399450068741;4924.96575719103;5147.279380886679;5258.97370513145;5803.738831752581;4500.572445617661;4862.562369386701;4964.801407943714;5031.641456806642;5161.4322424533275;2559.590465525544;2625.4617803350266;2894.261963199413;3014.5478178273434;3069.956214886949;5342.786288548492;5387.515123638891;5836.591438599342;6064.089237166185;6130.693465326716;4791.688540244873;5379.740508986235;5692.815780401402;5752.36723960364;5952.523798096142;5065.0116431305705;5266.2890509781455;5986.55100867428;6022.819315420545;6037.366076561898;4568.611668008257;5721.828345149652;5786.884262314814;5882.764619429968;5914.467697907209;4937.716418581746;5210.713410762444;5253.343332083489;5687.876289689942;5752.338481074563;4994.1260279450225;5407.729183641763;5526.22632831412;5709.886572118397;6073.45126992614;4562.315652651996;4572.88567785808;4595.471520775153;4596.908061838812;4614.66174809315;5123.567209969255;5259.789608415621;5339.745159887501;5773.566982476415;5867.061270196748;5066.138756411493;5285.656432331886;5621.8594535136945;5632.915506267327;5656.60403771732;5199.532042116228;5202.453742357149;5359.088954877602;5605.215269862304;5638.4925356718095;4790.568848803485;5192.45479224676;5464.3442786865835;5504.559635229092;5511.448855114457;5013.044912484062;5501.771782428123;5556.526297396503;5602.635828671123;5662.603617746719;4782.52174782519;5133.716570857286;5487.890242195054;5498.3127109111365;5501.707098831691 13:29:39 INFO - PROCESS | 6918 | |5;yui.html;591.3790519954047;743.4201322968083;764.0792844622231;819.4018366592386;829.3531045784287;15148.091114533121;16032.198390080508;16068.839311606895;16117.83882161178;16144.83855161448;15676.764848527282;16668.08325542095;17001;17151.82848171518;17322.913385433054;894.6823877523483;932.1796818799447;952.4856577448204;972.2756546372926;972.9978122533767;899.2715900120891;955.0974329258853;958.1903291718521;969.839976403896;985.6156099121348;992.3847214726843;1005.0703099632847;1008.9646862359782;1009.106940357785;1009.1119814563211;979.7011911367031;997.1723469520281;1002;1002.6240159940022;1003.4531180506605;749.232037161908;749.8275396658745;751.2112282103769;751.3838652305112;752.0636807175048;15901.840981590267;16228.188590570424;16269.267882945296;16822.747658785127;17081.743773843402;14494.202818844959;14553.199574023425;14659.926700366537;14665.193414362206;14821;11539.48072336747;11718.414079296;11768.58809941648;11779.941100294529;12292.323922184276;3715.3498137825773;3715.4612581175847;3756.940068569612;3759.0978165240417;3775.735698501106;90484;92377.538112309;114066;115678;121358.78641213563;11368.261063030877;11368.772624547628;11393.259438136494;11459.77080458386;11782;20820.37538873836;23545.29364119079;24394.75605243925;24426.633600496007;24441.87779061129;7768.316515773954;7869.1737367576725;7877.7636670899965;8018.639161237758;8082.353411727047;4260.659147268211;4266.658667306608;4354.608085272341;4420.535843736361;4529.6376289896725;3542.309249696336;3608.2061946371755;3618.185908170693;3621.5292012038267;3622.185008373095;536.6994483089483;623.4015345268593;630.2059405149453;637.4708991537013;654.3554598720257;659.9901001484982;669.7990602819232;689.7447944260656;693.1405057728381;711.9323664251888;9780.364276322016;10111.3427627204;10178.389296642225;10595.417252051211;11023.61417350389;571.1860598646995;576.7231728770214;589.917411562373;629.9055141728777;682.1711620381261;4364.890877728082;4981.526754958273;4990.426100998359;5023.824166154167;5046.899062018739;83173.58413207895;84294.20676069592;87162.56418717866;100864;101868.98131018743;89762;90978.54510727404;91184.54407727919;92339.53830230806;92454;78373.60813195897;79018.41963160704;79980.60009700031;80568.59715701384;81815;9638.987187613728;9696.0788725071;9715.56279967389;9721.562529686322;9772.657956971472;45730;49354.75322623364;50110.749446252536;51446.742766285926;52121.95756084933;64279.67860160669;65527.672361639794;68727.31272687209;69208.30791692018;73405;12701.745965080461;12856.678583035498;13073.803892941423;13434.462621495422;13727.45090196381;14486.927565362106;14642.780358294422;15260.771088433461;15272.770908436605;15728.370865165723;11905.166638335233;13478.595642130751;13711;14053.718925621635;14649.92675036618;10947.616833410792;13346.332683365987;13990.650233744238;13994.16035037901;13994.16035037901;13042.739145216852;13634.181949083088;13733.725325493633;13749.175049497062;13756.449742010207;9877.802443951225;9996.60013599477;10080.395176289447;10150.898491014996;10541.420221887869;11026;11205.60780372683;11396.601118961129;11484.540618375171;11517.366544839779 13:29:39 INFO - PROCESS | 6918 | __end_tp_report 13:29:39 INFO - PROCESS | 6918 | __start_cc_report 13:29:39 INFO - PROCESS | 6918 | _x_x_mozilla_cycle_collect,414 13:29:39 INFO - PROCESS | 6918 | __end_cc_report 13:29:39 INFO - PROCESS | 6918 | __startTimestamp1491078579962__endTimestamp 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | ------- Summary: start ------- 13:29:39 INFO - PROCESS | 6918 | Number of tests: 6 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#0] dojo.html Cycles:165 Average:5957.01 Median:6232.50 stddev:707.39 (11.4%) stddev-sans-first:669.15 13:29:39 INFO - PROCESS | 6918 | Values: 2952.5 3417.8 3441.5 3458.8 3475.0 5555.0 5582.4 6146.8 6148.7 6159.5 6081.7 6116.6 6132.1 6152.1 6164.1 5441.8 5484.2 5507.2 5511.4 5562.5 4593.0 5555.8 5570.0 5580.4 5591.6 4466.4 5335.3 5487.8 5573.4 5591.7 4760.7 5169.5 5599.3 5602.6 5637.8 5435.2 5499.1 5505.8 5507.4 5527.8 5753.4 5873.9 6008.5 6011.1 6185.8 6310.5 6348.7 6365.8 6424.7 6429.0 6281.8 6587.2 6589.6 6592.2 6592.2 6258.2 6280.2 6290.7 6300.8 6318.7 6232.5 6371.3 6466.4 6613.9 6619.4 5169.9 6327.1 6395.4 6404.4 6431.6 5789.7 6526.4 6537.8 6545.5 6572.3 6257.6 6280.0 6312.0 6327.4 6343.0 5313.8 6146.0 6420.7 6504.4 6518.2 5189.5 5190.1 5218.1 5253.9 5291.7 4263.5 4630.8 4641.7 4652.4 4672.2 5871.0 5936.2 5937.7 5946.1 6016.1 5383.1 6488.2 6492.4 6537.8 6551.1 5189.3 5415.8 6177.4 6183.4 6192.3 5567.5 5605.9 5640.9 5791.2 5833.3 6566.4 6636.3 6651.7 6656.0 6658.0 5812.8 6464.1 6566.7 6573.9 6584.9 6373.2 6713.2 6751.3 6764.0 6794.3 6302.6 6324.0 6383.8 6502.2 6503.1 6564.7 6567.6 6567.9 6571.2 6586.0 6339.1 6348.9 6383.9 6388.8 6410.8 6176.6 6227.0 6266.1 6273.8 6352.5 5421.7 6044.9 6226.3 6338.7 6368.3 6243.6 6252.9 6307.8 6333.9 6354.8 6196.1 6258.7 6276.6 6290.7 6312.8 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#1] ext.html Cycles:180 Average:55389.97 Median:27855.37 stddev:117401.41 (421.5%) stddev-sans-first:117658.94 13:29:39 INFO - PROCESS | 6918 | Values: 698.6 729.8 771.5 793.8 813.0 38047.0 38842.0 38868.0 38870.0 38946.2 39815.6 40020.4 40067.6 40142.0 40177.8 9213.6 9342.5 9380.6 10345.7 10608.0 10416.2 10445.8 10453.6 10521.6 10523.1 9008.4 9266.6 10521.9 10544.8 10563.5 10456.6 10490.8 10509.8 10528.6 10532.6 10316.9 10327.9 10397.8 10403.3 10405.1 32781.8 32917.0 33005.0 33136.5 33203.8 3661.9 3742.3 3744.2 3763.8 3783.2 24328.4 24464.8 24971.9 25806.9 25953.0 2974.8 3043.0 3100.5 3149.1 3250.2 332654.0 348800.8 349202.0 349559.0 350880.0 42532.8 42930.0 42940.0 43121.1 43271.6 44767.6 44807.1 44875.9 44921.8 44968.8 13662.2 13673.9 13796.0 13821.2 13841.6 11765.9 11769.4 11906.6 11941.0 11980.3 10040.4 11604.2 11665.7 11681.9 11758.9 4824.0 5323.9 5490.1 5543.2 5561.3 1009.2 1063.9 1067.2 1090.5 1120.4 22560.9 25345.0 25710.5 26426.8 26546.6 1197.8 1223.8 1260.9 1277.9 1283.7 11762.9 13514.3 13614.5 13660.7 13817.8 477658.0 477835.0 479453.0 481609.0 482166.0 56879.7 57087.1 57109.4 57306.4 57706.4 1381.5 1395.8 1419.7 1430.9 1434.6 25599.0 26341.9 26587.6 26760.1 26855.9 39325.6 39452.6 39456.8 39471.6 39596.0 473436.0 482467.0 483547.0 484706.0 484872.0 27672.9 28037.9 28120.6 28144.0 28154.6 36416.3 36515.6 36518.9 36964.6 37343.0 25009.7 25944.2 26490.5 26683.0 26792.0 26241.7 26344.6 26684.5 26980.3 27159.0 30291.4 31107.5 31301.9 31402.9 31486.0 20844.8 23626.1 24557.6 26512.6 27020.6 20627.8 22087.1 22102.8 22146.9 22162.6 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#2] jquery.html Cycles:180 Average:43157.61 Median:11591.73 stddev:129959.41 (1121.1%) stddev-sans-first:130284.31 13:29:39 INFO - PROCESS | 6918 | Values: 397.7 403.7 404.8 417.9 437.7 19633.3 20451.5 20624.0 20746.5 20956.5 13938.5 15346.0 15355.4 16225.6 21491.5 6377.2 6658.1 6948.7 7306.1 7369.5 6737.5 7039.8 7158.4 7292.0 7322.6 7372.8 7426.2 7427.9 7436.0 7473.3 6619.2 6946.5 7101.0 7320.2 7391.7 6943.8 7127.5 7134.4 7156.4 7166.8 21596.4 22866.4 24284.5 24439.0 24710.6 2895.7 2992.0 3112.3 3120.8 3277.0 14612.1 15272.9 15293.4 15606.9 15724.9 2067.8 2071.8 2077.3 2104.3 2126.4 138519.0 138634.0 141294.3 143903.0 145209.3 15808.5 18951.9 19113.0 19738.7 19814.1 26745.0 27136.5 27222.7 27256.5 27325.2 9920.4 9921.5 9925.5 9946.0 10236.4 7211.5 8496.7 8612.4 8652.7 8816.5 8712.8 8789.3 8850.8 8871.5 8887.7 3628.9 4084.2 4123.5 4155.1 4157.9 814.6 915.1 1008.8 1031.8 1072.0 19545.9 20616.5 20926.6 21079.2 21188.0 944.7 1051.4 1072.1 1083.2 1103.0 8902.3 10194.6 10811.0 10814.9 10854.5 771608.0 778672.0 783358.0 783984.0 792239.0 123690.0 154816.0 156296.2 159549.0 161465.2 1034.6 1112.0 1223.5 1268.7 1270.2 17817.0 18852.3 19058.1 19135.9 19142.8 58441.0 62172.7 62312.4 63684.7 66734.0 89862.1 90506.5 91021.0 91531.0 91825.1 11241.2 11385.8 11476.0 11488.2 11730.5 12599.7 12881.4 12956.8 12982.4 13027.0 10553.1 10815.1 10873.1 10948.9 11016.4 10131.1 10326.2 10371.9 10625.2 10725.5 8893.6 9193.6 11040.9 11108.1 11369.3 11264.7 11265.4 11618.2 11688.7 11727.9 10700.9 10778.4 10792.6 10928.0 11121.7 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#3] mootools.html Cycles:180 Average:6748.03 Median:2415.94 stddev:4195.52 (173.7%) stddev-sans-first:4188.79 13:29:39 INFO - PROCESS | 6918 | Values: 1505.9 1537.3 1560.5 1566.9 1577.9 9412.8 9613.4 10626.4 11127.0 11146.9 9282.8 10511.9 10857.9 10871.8 10883.6 2365.8 2396.2 2416.5 2416.9 2425.6 2366.8 2373.3 2397.3 2414.0 2415.4 2361.8 2380.6 2387.7 2394.4 2397.0 2017.5 2337.6 2349.8 2366.0 2374.7 2358.4 2364.7 2374.9 2381.2 2385.8 11149.6 11203.6 11241.9 11365.2 11392.8 5740.1 5836.2 5854.6 5932.6 5938.9 3166.9 3488.8 3598.2 3625.0 3657.8 1158.8 1158.8 1189.7 1210.1 1211.2 13652.4 15007.3 15539.2 15601.1 15618.5 4674.3 4779.5 4807.7 4847.3 4875.7 12705.9 12740.9 12743.2 12743.2 12745.2 4931.4 5836.7 5853.6 5912.7 5926.8 4442.6 4884.0 5214.9 5267.3 5356.5 3931.4 4021.3 4084.8 4092.8 4160.0 940.5 1039.7 1044.4 1117.3 1130.8 810.5 942.6 950.8 964.6 969.9 6472.6 6563.7 6590.1 6746.1 6827.4 882.4 993.9 1002.4 1010.7 1057.6 4242.7 4252.4 4255.9 4262.9 4396.3 11620.5 13565.9 13593.7 13619.1 13664.9 11133.0 13430.5 13549.3 13549.5 13588.0 10137.9 10196.3 10198.7 10222.8 10234.0 6532.3 6552.4 6625.7 6629.9 6877.3 11753.6 12091.1 12126.8 12216.8 12233.8 12209.9 12420.4 12497.4 12501.1 12584.9 8116.2 8255.0 9007.1 9077.3 9323.3 10521.3 10544.9 10601.2 10617.4 10627.4 9139.0 9213.9 9216.5 9305.6 9373.6 8875.8 9157.8 9184.3 9234.2 9339.3 7880.9 7990.3 8038.2 8088.4 8089.2 5881.5 5945.2 6003.8 6104.1 6191.8 5972.7 6095.7 6105.1 6224.9 6254.2 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#4] prototype.html Cycles:180 Average:4654.77 Median:5004.04 stddev:1281.53 (25.6%) stddev-sans-first:1243.07 13:29:39 INFO - PROCESS | 6918 | Values: 316.6 362.3 364.5 383.8 384.7 5111.2 5219.8 5247.3 5314.0 5445.5 5139.9 5181.3 5271.8 5273.5 5314.0 4199.2 4239.8 4568.9 4581.9 4585.4 4136.6 4325.9 4500.9 4617.8 4630.7 3873.2 4213.8 4525.3 4551.9 4600.2 4008.8 4288.9 4542.7 4546.3 4626.4 3438.5 4377.0 4378.4 4382.6 4389.2 4317.6 4793.5 4917.6 5153.6 5202.2 4620.0 4634.8 5079.5 5086.7 5096.3 4410.9 5539.0 5693.1 5715.4 5770.7 3676.7 4078.9 4306.2 4344.7 4499.3 5511.9 5679.4 5797.4 5819.0 5874.8 4081.5 4361.8 4571.0 4582.0 4586.0 4146.9 4464.4 4660.5 4669.3 4702.8 4091.4 4364.7 4731.6 4978.5 5016.6 4836.6 5204.0 5363.9 5436.0 5572.6 1482.9 1557.5 1569.6 1592.7 1605.6 1089.2 1159.6 1177.7 1199.8 1201.4 4442.4 4675.8 4909.8 4995.0 5159.3 4804.4 4925.0 5147.3 5259.0 5803.7 4500.6 4862.6 4964.8 5031.6 5161.4 2559.6 2625.5 2894.3 3014.5 3070.0 5342.8 5387.5 5836.6 6064.1 6130.7 4791.7 5379.7 5692.8 5752.4 5952.5 5065.0 5266.3 5986.6 6022.8 6037.4 4568.6 5721.8 5786.9 5882.8 5914.5 4937.7 5210.7 5253.3 5687.9 5752.3 4994.1 5407.7 5526.2 5709.9 6073.5 4562.3 4572.9 4595.5 4596.9 4614.7 5123.6 5259.8 5339.7 5773.6 5867.1 5066.1 5285.7 5621.9 5632.9 5656.6 5199.5 5202.5 5359.1 5605.2 5638.5 4790.6 5192.5 5464.3 5504.6 5511.4 5013.0 5501.8 5556.5 5602.6 5662.6 4782.5 5133.7 5487.9 5498.3 5501.7 13:29:39 INFO - PROCESS | 6918 | 13:29:39 INFO - PROCESS | 6918 | [#5] yui.html Cycles:180 Average:20558.22 Median:3736.20 stddev:28965.77 (775.3%) stddev-sans-first:29008.22 13:29:39 INFO - PROCESS | 6918 | Values: 591.4 743.4 764.1 819.4 829.4 15148.1 16032.2 16068.8 16117.8 16144.8 15676.8 16668.1 17001.0 17151.8 17322.9 894.7 932.2 952.5 972.3 973.0 899.3 955.1 958.2 969.8 985.6 992.4 1005.1 1009.0 1009.1 1009.1 979.7 997.2 1002.0 1002.6 1003.5 749.2 749.8 751.2 751.4 752.1 15901.8 16228.2 16269.3 16822.7 17081.7 14494.2 14553.2 14659.9 14665.2 14821.0 11539.5 11718.4 11768.6 11779.9 12292.3 3715.3 3715.5 3756.9 3759.1 3775.7 90484.0 92377.5 114066.0 115678.0 121358.8 11368.3 11368.8 11393.3 11459.8 11782.0 20820.4 23545.3 24394.8 24426.6 24441.9 7768.3 7869.2 7877.8 8018.6 8082.4 4260.7 4266.7 4354.6 4420.5 4529.6 3542.3 3608.2 3618.2 3621.5 3622.2 536.7 623.4 630.2 637.5 654.4 660.0 669.8 689.7 693.1 711.9 9780.4 10111.3 10178.4 10595.4 11023.6 571.2 576.7 589.9 629.9 682.2 4364.9 4981.5 4990.4 5023.8 5046.9 83173.6 84294.2 87162.6 100864.0 101869.0 89762.0 90978.5 91184.5 92339.5 92454.0 78373.6 79018.4 79980.6 80568.6 81815.0 9639.0 9696.1 9715.6 9721.6 9772.7 45730.0 49354.8 50110.7 51446.7 52122.0 64279.7 65527.7 68727.3 69208.3 73405.0 12701.7 12856.7 13073.8 13434.5 13727.5 14486.9 14642.8 15260.8 15272.8 15728.4 11905.2 13478.6 13711.0 14053.7 14649.9 10947.6 13346.3 13990.7 13994.2 13994.2 13042.7 13634.2 13733.7 13749.2 13756.4 9877.8 9996.6 10080.4 10150.9 10541.4 11026.0 11205.6 11396.6 11484.5 11517.4 13:29:39 INFO - PROCESS | 6918 | -------- Summary: end -------- 13:29:39 INFO - PROCESS | 6918 | 13:29:40 INFO - TEST-INFO | 6918: exit 0 13:29:40 INFO - TEST-OK | dromaeo_css | took 1116905ms 13:29:40 INFO - TEST-START | kraken 13:29:40 INFO - operating with platform_type : mac_ 13:29:40 INFO - Initialising browser for kraken test... 13:29:40 INFO - TEST-INFO | started process 6923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpjELeJl/profile http://localhost:49665/getInfo.html) 13:29:43 INFO - PROCESS | 6923 | 2017-04-01 13:29:43.425 plugin-container[6924:138713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a37, name = 'com.apple.tsm.portname' 13:29:43 INFO - PROCESS | 6923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:29:43 INFO - PROCESS | 6923 | 2017-04-01 13:29:43.428 plugin-container[6924:138713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 13:29:43 INFO - PROCESS | 6923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:29:43 INFO - PROCESS | 6923 | 2017-04-01 13:29:43.428 plugin-container[6924:138713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:29:43 INFO - PROCESS | 6923 | __metrics Screen width/height:1600/1200 13:29:43 INFO - PROCESS | 6923 | colorDepth:24 13:29:43 INFO - PROCESS | 6923 | Browser inner width/height: 1024/689 13:29:43 INFO - PROCESS | 6923 | __metrics 13:29:43 ERROR - PROCESS | 6923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:29:44 INFO - TEST-INFO | 6923: exit 0 13:29:44 INFO - Browser initialized. 13:29:44 INFO - Running cycle 1/1 for kraken test... 13:29:44 INFO - TEST-INFO | started process 6925 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpjELeJl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:29:46 INFO - PROCESS | 6925 | 2017-04-01 13:29:46.917 plugin-container[6926:138839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 13:29:46 INFO - PROCESS | 6925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:29:46 INFO - PROCESS | 6925 | 2017-04-01 13:29:46.918 plugin-container[6926:138839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 13:29:46 INFO - PROCESS | 6925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:29:46 INFO - PROCESS | 6925 | 2017-04-01 13:29:46.918 plugin-container[6926:138839] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:29:56 INFO - PROCESS | 6925 | RSS: Main: 221020160 13:29:56 INFO - PROCESS | 6925 | 13:29:59 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-beat-detection) 13:30:00 INFO - PROCESS | 6925 | RSS: Main: 235429888 13:30:00 INFO - PROCESS | 6925 | 13:30:03 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-dft) 13:30:03 INFO - PROCESS | 6925 | RSS: Main: 243494912 13:30:03 INFO - PROCESS | 6925 | 13:30:06 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-fft) 13:30:07 INFO - PROCESS | 6925 | RSS: Main: 247132160 13:30:07 INFO - PROCESS | 6925 | 13:30:09 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49665/tests/kraken/driver.html?testName=audio-oscillator) 13:30:10 INFO - PROCESS | 6925 | RSS: Main: 247832576 13:30:10 INFO - PROCESS | 6925 | 13:30:12 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:30:12 INFO - PROCESS | 6925 | RSS: Main: 247607296 13:30:12 INFO - PROCESS | 6925 | 13:30:15 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-darkroom) 13:30:16 INFO - PROCESS | 6925 | RSS: Main: 252915712 13:30:16 INFO - PROCESS | 6925 | 13:30:19 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49665/tests/kraken/driver.html?testName=imaging-desaturate) 13:30:20 INFO - PROCESS | 6925 | RSS: Main: 252420096 13:30:20 INFO - PROCESS | 6925 | 13:30:23 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49665/tests/kraken/driver.html?testName=json-parse-financial) 13:30:23 INFO - PROCESS | 6925 | RSS: Main: 252919808 13:30:23 INFO - PROCESS | 6925 | 13:30:25 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49665/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:30:26 INFO - PROCESS | 6925 | RSS: Main: 251916288 13:30:26 INFO - PROCESS | 6925 | 13:30:28 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:30:28 INFO - PROCESS | 6925 | RSS: Main: 251994112 13:30:28 INFO - PROCESS | 6925 | 13:30:31 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:30:31 INFO - PROCESS | 6925 | RSS: Main: 252051456 13:30:31 INFO - PROCESS | 6925 | 13:30:34 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:30:35 INFO - PROCESS | 6925 | RSS: Main: 252870656 13:30:35 INFO - PROCESS | 6925 | 13:30:38 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:30:39 INFO - PROCESS | 6925 | RSS: Main: 260722688 13:30:39 INFO - PROCESS | 6925 | 13:30:40 INFO - PROCESS | 6925 | Cycle 1(1): loaded http://localhost:49665/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49665/tests/kraken/driver.html?testName=ai-astar) 13:30:41 INFO - PROCESS | 6925 | RSS: Main: 262533120 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | __start_tp_report 13:30:41 INFO - PROCESS | 6925 | _x_x_mozilla_page_load 13:30:41 INFO - PROCESS | 6925 | _x_x_mozilla_page_load_details 13:30:41 INFO - PROCESS | 6925 | |i|pagename|runs| 13:30:41 INFO - PROCESS | 6925 | |0;ai-astar;109;121;120;120;119;120;124;121;121;123 13:30:41 INFO - PROCESS | 6925 | |1;audio-beat-detection;145;112;112;146;110;115;109;114;130;116 13:30:41 INFO - PROCESS | 6925 | |2;audio-dft;196;208;197;204;200;200;203;189;205;201 13:30:41 INFO - PROCESS | 6925 | |3;audio-fft;85;61;64;89;62;63;62;62;78;61 13:30:41 INFO - PROCESS | 6925 | |4;audio-oscillator;90;71;86;72;71;71;83;96;101;112 13:30:41 INFO - PROCESS | 6925 | |5;imaging-gaussian-blur;96;89;90;88;89;90;89;89;89;91 13:30:41 INFO - PROCESS | 6925 | |6;imaging-darkroom;98;98;97;125;153;97;97;96;97;96 13:30:41 INFO - PROCESS | 6925 | |7;imaging-desaturate;117;97;73;146;149;91;91;106;92;90 13:30:41 INFO - PROCESS | 6925 | |8;json-parse-financial;66;64;66;65;68;68;69;68;68;65 13:30:41 INFO - PROCESS | 6925 | |9;json-stringify-tinderbox;48;83;89;57;80;88;84;83;50;76 13:30:41 INFO - PROCESS | 6925 | |10;stanford-crypto-aes;83;98;98;85;84;84;87;88;81;89 13:30:41 INFO - PROCESS | 6925 | |11;stanford-crypto-ccm;139;139;156;149;162;149;150;175;184;152 13:30:41 INFO - PROCESS | 6925 | |12;stanford-crypto-pbkdf2;172;158;228;204;166;169;172;181;148;193 13:30:41 INFO - PROCESS | 6925 | |13;stanford-crypto-sha256-iterative;67;67;66;68;68;67;65;66;68;69 13:30:41 INFO - PROCESS | 6925 | __end_tp_report 13:30:41 INFO - PROCESS | 6925 | __start_cc_report 13:30:41 INFO - PROCESS | 6925 | _x_x_mozilla_cycle_collect,1015 13:30:41 INFO - PROCESS | 6925 | __end_cc_report 13:30:41 INFO - PROCESS | 6925 | __startTimestamp1491078641111__endTimestamp 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | ------- Summary: start ------- 13:30:41 INFO - PROCESS | 6925 | Number of tests: 14 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#0] ai-astar Cycles:10 Average:119.80 Median:121.00 stddev:4.08 (3.4%) stddev-sans-first:1.58 13:30:41 INFO - PROCESS | 6925 | Values: 109.0 121.0 120.0 120.0 119.0 120.0 124.0 121.0 121.0 123.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:115.50 stddev:14.22 (12.3%) stddev-sans-first:12.11 13:30:41 INFO - PROCESS | 6925 | Values: 145.0 112.0 112.0 146.0 110.0 115.0 109.0 114.0 130.0 116.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#2] audio-dft Cycles:10 Average:200.30 Median:202.00 stddev:5.38 (2.7%) stddev-sans-first:5.47 13:30:41 INFO - PROCESS | 6925 | Values: 196.0 208.0 197.0 204.0 200.0 200.0 203.0 189.0 205.0 201.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#3] audio-fft Cycles:10 Average:68.70 Median:63.50 stddev:10.91 (17.2%) stddev-sans-first:9.85 13:30:41 INFO - PROCESS | 6925 | Values: 85.0 61.0 64.0 89.0 62.0 63.0 62.0 62.0 78.0 61.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:77.50 stddev:14.50 (18.7%) stddev-sans-first:15.28 13:30:41 INFO - PROCESS | 6925 | Values: 90.0 71.0 86.0 72.0 71.0 71.0 83.0 96.0 101.0 112.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#5] imaging-gaussian-blur Cycles:10 Average:90.00 Median:89.50 stddev:2.26 (2.5%) stddev-sans-first:0.87 13:30:41 INFO - PROCESS | 6925 | Values: 96.0 89.0 90.0 88.0 89.0 90.0 89.0 89.0 89.0 91.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#6] imaging-darkroom Cycles:10 Average:105.40 Median:97.00 stddev:18.91 (19.5%) stddev-sans-first:19.87 13:30:41 INFO - PROCESS | 6925 | Values: 98.0 98.0 97.0 125.0 153.0 97.0 97.0 96.0 97.0 96.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:90.50 stddev:25.02 (27.7%) stddev-sans-first:26.17 13:30:41 INFO - PROCESS | 6925 | Values: 117.0 97.0 73.0 146.0 149.0 91.0 91.0 106.0 92.0 90.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:68.00 stddev:1.70 (2.5%) stddev-sans-first:1.79 13:30:41 INFO - PROCESS | 6925 | Values: 66.0 64.0 66.0 65.0 68.0 68.0 69.0 68.0 68.0 65.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:83.00 stddev:15.86 (19.1%) stddev-sans-first:13.80 13:30:41 INFO - PROCESS | 6925 | Values: 48.0 83.0 89.0 57.0 80.0 88.0 84.0 83.0 50.0 76.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.50 stddev:5.93 (6.8%) stddev-sans-first:6.04 13:30:41 INFO - PROCESS | 6925 | Values: 83.0 98.0 98.0 85.0 84.0 84.0 87.0 88.0 81.0 89.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:154.00 stddev:14.55 (9.5%) stddev-sans-first:14.16 13:30:41 INFO - PROCESS | 6925 | Values: 139.0 139.0 156.0 149.0 162.0 149.0 150.0 175.0 184.0 152.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:176.50 stddev:23.56 (13.3%) stddev-sans-first:24.85 13:30:41 INFO - PROCESS | 6925 | Values: 172.0 158.0 228.0 204.0 166.0 169.0 172.0 181.0 148.0 193.0 13:30:41 INFO - PROCESS | 6925 | 13:30:41 INFO - PROCESS | 6925 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.20 (1.8%) stddev-sans-first:1.27 13:30:41 INFO - PROCESS | 6925 | Values: 67.0 67.0 66.0 68.0 68.0 67.0 65.0 66.0 68.0 69.0 13:30:41 INFO - PROCESS | 6925 | -------- Summary: end -------- 13:30:41 INFO - PROCESS | 6925 | 13:30:42 INFO - TEST-INFO | 6925: exit 0 13:30:42 INFO - TEST-OK | kraken | took 61538ms 13:30:42 INFO - SUITE-END | took 1178s 13:30:42 INFO - Completed test suite (00:19:38) 13:30:42 INFO - javascript benchmark 13:30:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7655.246987772038, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2952.54235593483, 3417.8461969211367, 3441.4665726812354, 3458.7751796133266, 3474.9922522468487, 5555.027870122727, 5582.358028826686, 6146.81559553213, 6148.692565371724, 6159.476444502211, 6081.695915204245, 6116.6024208426425, 6132.080187971796, 6152.077188421739, 6164.075388691698, 5441.83674489766, 5484.232207490954, 5507.173923911425, 5511.366192887829, 5562.471565201319, 4592.97703511482, 5555.777768889239, 5569.972150139243, 5580.358258800229, 5591.580631452637, 4466.352378905057, 5335.279737235463, 5487.807926722566, 5573.386927437979, 5591.664500129985, 4760.666753327268, 5169.526684894787, 5599.272094627713, 5602.60781745278, 5637.830865074055, 5435.1847222916485, 5499.092649712793, 5505.779768809243, 5507.394186639466, 5527.7560204148, 5753.367129615739, 5873.941260587425, 6008.549358798107, 6011.1283863839935, 6185.845353866144, 6310.495160387158, 6348.746050157988, 6365.809025729235, 6424.678766061678, 6428.967855160741, 6281.775053864499, 6587.176602924635, 6589.637569933651, 6592.17597800275, 6592.208934927791, 6258.15514905491, 6280.15217945576, 6290.716917738713, 6300.810975670737, 6318.74725010999, 6232.501399887998, 6371.33101024395, 6466.418022377915, 6613.933860661428, 6619.371159739817, 5169.896602068013, 6327.050942358683, 6395.360463953567, 6404.391582799627, 6431.5819471734185, 5789.681567513743, 6526.379993900572, 6537.771178008747, 6545.476361891037, 6572.34276572339, 6257.561970662101, 6279.995200767855, 6311.96844015777, 6327.3988971047675, 6342.968285158545, 5313.814016509404, 6145.985912324416, 6420.711068001951, 6504.38208370204, 6518.152640156749, 5189.481051894781, 5190.117679994334, 5218.112920803473, 5253.947460525423, 5291.656042357235, 4263.531011588722, 4630.768461576908, 4641.6518761092375, 4652.441706995182, 4672.229082201467, 5871.0, 5936.198613187251, 5937.732802023833, 5946.07835785454, 6016.067509536029, 5383.111786555253, 6488.221413430419, 6492.35076492347, 6537.771178008747, 6551.1155993941165, 5189.273501709764, 5415.756290966916, 6177.351378105234, 6183.412575805291, 6192.256929168439, 5567.498925096761, 5605.887882242332, 5640.8871822563315, 5791.247137872049, 5833.270841144857, 6566.376194261537, 6636.303188165173, 6651.7006734698025, 6655.966720166368, 6657.966710166418, 5812.796552120655, 6464.095026696173, 6566.704498297492, 6573.934260657524, 6584.868302634016, 6373.171487706569, 6713.160854893138, 6751.324867513209, 6764.019217213378, 6794.286599906938, 6302.5588208826775, 6324.019776934582, 6383.840403989938, 6502.187226596676, 6503.122078519528, 6564.737410503526, 6567.573107747981, 6567.9014814776865, 6571.244306904767, 6586.012098185311, 6339.112524246517, 6348.936510634834, 6383.87232255343, 6388.840278993062, 6410.8076757697345, 6176.629402235881, 6227.034809604518, 6266.122742816101, 6273.843153921189, 6352.523560733056, 5421.725894414888, 6044.90932636002, 6226.315105338319, 6338.651374174465, 6368.331325210971, 6243.562950593415, 6252.937470625236, 6307.810765677037, 6333.936660633335, 6354.777582784581, 6196.1015652731, 6258.655773932477, 6276.592021518587, 6290.654014029273, 6312.810615681537], "value": 5911.727238888336, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.5773606967784, 729.7847135095146, 771.479251505234, 793.7817100297419, 812.9593520323986, 38047.0, 38842.02894927628, 38868.0, 38870.0, 38946.221075578505, 39815.60184398155, 40020.39969400461, 40067.599324006755, 40142.0, 40177.7991110044, 9213.631454741819, 9342.532873356338, 9380.624775008991, 10345.741356466073, 10608.045275925166, 10416.166706663449, 10445.84331235032, 10453.581856725721, 10521.579136834518, 10523.105536029447, 9008.369414141012, 9266.629334826632, 10521.894781052168, 10544.841827372557, 10563.471826408688, 10456.634017809416, 10490.842637360445, 10509.84235236472, 10528.578856845794, 10532.631357902514, 10316.948415257952, 10327.948360258151, 10397.79204415915, 10403.323783954018, 10405.063544280976, 32781.836090819634, 32917.0, 33005.0, 33136.50295245573, 33203.83398083018, 3661.9267614647556, 3742.288965096623, 3744.1762812181278, 3763.849446022156, 3783.205526839367, 24328.39179020521, 24464.755352446427, 24971.87514062418, 25806.87096564524, 25952.961881524716, 2974.7917645764805, 3042.9847850760825, 3100.4884194107944, 3149.1497295730023, 3250.236194494292, 332654.0, 348800.7679884804, 349202.0, 349559.0, 350880.0, 42532.78733606312, 42930.0, 42940.0, 43121.137577248905, 43271.56728432675, 44767.55232447634, 44807.10385792266, 44875.87810304768, 44921.775391122836, 44968.77515612466, 13662.18026918388, 13673.86326136726, 13796.03427760047, 13821.170729756246, 13841.584752457442, 11765.941170294094, 11769.411529423494, 11906.642800715992, 11941.044716422666, 11980.281183128865, 10040.39757614531, 11604.187706860439, 11665.650030499099, 11681.941590292165, 11758.941205293919, 4824.044283687343, 5323.893522129536, 5490.051031883703, 5543.223948647193, 5561.304836895388, 1009.1674368645896, 1063.8510608514814, 1067.2102644043507, 1090.4547726136932, 1120.4117838135044, 22560.88719556392, 25344.98620055177, 25710.485790284463, 26426.810793514338, 26546.601800973, 1197.8261303922031, 1223.8041913294007, 1260.8802163794426, 1277.8530468996184, 1283.6919139406475, 11762.94118529419, 13514.256715880525, 13614.523491677943, 13660.65848353799, 13817.792733109012, 477658.0, 477835.0, 479453.0, 481609.0, 482166.0, 56879.715601422555, 57087.14369284464, 57109.42890571041, 57306.426935730946, 57706.42293577094, 1381.530279704885, 1395.818543589327, 1419.6876687128815, 1430.8712215900412, 1434.5624584501827, 25599.0, 26341.868290658425, 26587.60118598184, 26760.063397781763, 26855.86572067127, 39325.60674393219, 39452.605473944895, 39456.80271598624, 39471.605283947945, 39596.010099746585, 473436.0, 482467.0, 483547.0, 484706.0, 484872.0, 27672.861635691694, 28037.878484860375, 28120.57819132674, 28144.01495947714, 28154.577681334387, 36416.28308687712, 36515.63484365122, 36518.90443286706, 36964.63035369612, 37343.0, 25009.749902500742, 25944.221673349828, 26490.470190596465, 26683.0, 26792.0, 26241.737582624693, 26344.604830927936, 26684.466310674066, 26980.32549186286, 27159.0, 30291.394172115994, 31107.533386999665, 31301.904433344724, 31402.900898468444, 31486.0, 20844.791552084283, 23626.054957801494, 24557.649329287062, 26512.602310964965, 27020.594691079255, 20627.793722062586, 22087.1145187534, 22102.77897221007, 22146.889265553567, 22162.556748865256], "value": 17719.751427972016, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.7037107355021, 403.7395879657619, 404.7814180342616, 417.92686279901017, 437.73517022201565, 19633.312834050812, 20451.48871278219, 20624.0, 20746.481337966557, 20956.476088097806, 13938.51215207468, 15346.010037390275, 15355.385784568603, 16225.594360141002, 21491.46271343217, 6377.234731832175, 6658.067870498134, 6948.652567371611, 7306.123265208183, 7369.520981136236, 6737.494687898403, 7039.768040592901, 7158.427325813923, 7291.963540182292, 7322.597257150855, 7372.778816635509, 7426.220246874082, 7427.9257207428045, 7436.033315668956, 7473.252674732537, 6619.205695316593, 6946.548474349152, 7101.0, 7320.158181809107, 7391.704331826721, 6943.79168624942, 7127.501074924758, 7134.357907788324, 7156.355927966508, 7166.784996450115, 21596.352109436742, 22866.42833929165, 24284.514309713708, 24439.0, 24710.629340559728, 2895.6959519250595, 2991.9551206731917, 3112.3308488675043, 3120.7815452918303, 3276.950845737316, 14612.123272603678, 15272.930894837365, 15293.388264469519, 15606.921965390215, 15724.899257052011, 2067.8345732341377, 2071.78246284141, 2077.3352527191305, 2104.305579158889, 2126.362091372582, 138519.0, 138634.0, 141294.29352853273, 143902.99999999895, 145209.27395362954, 15808.52574422769, 18951.86288822675, 19113.0, 19738.70391944122, 19814.108365123604, 26745.0, 27136.457270854866, 27222.72777272202, 27256.45487090247, 27325.180244592695, 9920.355176913477, 9921.50392480373, 9925.503724813874, 9946.0, 10236.385816851012, 7211.459140564479, 8496.745097647081, 8612.397132200686, 8652.697155599524, 8816.51509167002, 8712.823768791131, 8789.340799440068, 8850.822983540293, 8871.467711937175, 8887.73336799897, 3628.909277268089, 4084.244414783275, 4123.454452124755, 4155.10665206982, 4157.91684166315, 814.5764202614725, 915.1260546178383, 1008.8234558952154, 1031.7626945802508, 1072.0030371754187, 19545.902270488557, 20616.484587885123, 20926.581468370547, 21079.156833726785, 21188.0, 944.6646440513656, 1051.400701600096, 1072.099436473366, 1083.2092572422175, 1102.9503672334765, 8902.332325075646, 10194.604267997014, 10811.027007569357, 10814.94592527048, 10854.511546980404, 771608.0, 778672.0, 783358.0, 783984.0, 792239.0, 123690.0, 154816.0, 156296.21851890668, 159549.0, 161465.19267403588, 1034.6068493972243, 1111.9666410007712, 1223.5350566784564, 1268.733565951142, 1270.1680399338447, 17817.0, 18852.3401680946, 19058.142383592494, 19135.90432047831, 19142.8085719141, 58441.0, 62172.68913655403, 62312.37687623247, 63684.68157659368, 66734.0, 89862.10137898538, 90506.54746726224, 91021.0, 91531.0, 91825.08174918166, 11241.156913231376, 11385.772284554429, 11476.0, 11488.19582629208, 11730.530778769094, 12599.748005039664, 12881.35593220354, 12956.805647915477, 12982.415791289226, 13027.0, 10553.102986245947, 10815.134789216687, 10873.118623564604, 10948.934139357518, 11016.394098324668, 10131.13885319756, 10326.225533084898, 10371.94814025925, 10625.233808015377, 10725.517351719036, 8893.599788009426, 9193.632254709737, 11040.944795275973, 11108.055815255793, 11369.31784092924, 11264.718382040186, 11265.436728163722, 11618.244814087057, 11688.70778230551, 11727.941360293486, 10700.892991070301, 10778.407187604756, 10792.56829726802, 10928.0, 11121.666350009513], "value": 11328.857914735445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.9247037648115, 1537.2621141851903, 1560.5162399656108, 1566.8824838137143, 1577.8501042400972, 9412.81174376512, 9613.42319460833, 10626.415547144903, 11127.0, 11146.888531114686, 9282.767930801734, 10511.947440262808, 10857.891421085787, 10871.83692244617, 10883.619073332435, 2365.787079162875, 2396.2451827674226, 2416.4804567018086, 2416.903323867043, 2425.599776036952, 2366.751491093436, 2373.2761507740133, 2397.280815755275, 2413.9517209655796, 2415.405832150781, 2361.7638236176417, 2380.6310021946624, 2387.6776635154292, 2394.4373072328076, 2397.0411835265945, 2017.5460521382645, 2337.555864385761, 2349.765023497637, 2366.0181024874655, 2374.715034195899, 2358.3632419246705, 2364.6571247169063, 2374.8693821839793, 2381.1546900850294, 2385.8210634202505, 11149.554017839277, 11203.551857925673, 11241.887581124165, 11365.204435689506, 11392.829107563393, 5740.13897915316, 5836.182934389189, 5854.560907931879, 5932.644041357532, 5938.881222375571, 3166.904992850218, 3488.8081155536433, 3598.1904071583945, 3624.9818750906074, 3657.7622454540638, 1158.8435561199215, 1158.8493495845487, 1189.6668932698858, 1210.074293165729, 1211.2066596379386, 13652.385642646104, 15007.32467038975, 15539.223038848126, 15601.063936163753, 15618.531444056696, 4674.271670875548, 4779.5220477951925, 4807.711537307773, 4847.345608342828, 4875.7074575525585, 12705.936470317589, 12740.93629531865, 12743.235405875677, 12743.235405875677, 12745.235285882876, 4931.3835770528685, 5836.737346819404, 5853.619514731528, 5912.704364781744, 5926.762929482772, 4442.5557444255965, 4883.975580122148, 5214.921776173361, 5267.26258323835, 5356.544693701001, 3931.4299426583557, 4021.2560676274984, 4084.7753373564738, 4092.8362865485046, 4160.0, 940.5438362394233, 1039.7419122861422, 1044.3629386074488, 1117.2737720481753, 1130.8077626803463, 810.5420437452821, 942.6088173408097, 950.8098380323963, 964.6382606522554, 969.8642190093393, 6472.579282346726, 6563.671816409161, 6590.110335104795, 6746.123004009447, 6827.419669328064, 882.3999680217514, 993.8956409577034, 1002.3634991780272, 1010.7372083258406, 1057.603398725478, 4242.681798865097, 4252.4471818664, 4255.893602659897, 4262.850800221977, 4396.296592545177, 11620.477078531487, 13565.932170339085, 13593.660158496117, 13619.114757540729, 13664.93167534156, 11133.0, 13430.54375214322, 13549.322533873266, 13549.45802167922, 13588.0, 10137.949310253549, 10196.3372380795, 10198.694039178688, 10222.846657300146, 10234.02776736209, 6532.346765323429, 6552.443042341454, 6625.734970601122, 6629.867402651824, 6877.346652068046, 11753.588624398106, 12091.093168012258, 12126.818097728717, 12216.755664886474, 12233.816492752438, 12209.877901220874, 12420.441080151595, 12497.437615307515, 12501.124921255425, 12584.937075314565, 8116.188381161836, 8254.958725206336, 9007.09929007094, 9077.273818094405, 9323.300752443458, 10521.316114452537, 10544.89455105439, 10601.151907847508, 10617.362958222531, 10627.36235825853, 9139.0, 9213.861792073258, 9216.493092879957, 9305.581248843953, 9373.578188981648, 8875.778105547413, 9157.77105572366, 9184.311176661644, 9234.1689247968, 9339.252859771337, 7880.881786773089, 7990.280874721303, 8038.196180382149, 8088.352931765562, 8089.2315230053055, 5881.470667639933, 5945.197398351167, 6003.7898673546215, 6104.145419641165, 6191.752329906753, 5972.701364931823, 6095.725692343766, 6105.145279660762, 6224.906626400517, 6254.249490061222], "value": 5148.396363319887, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.59792064078607, 362.34958249941354, 364.52247555702013, 383.80042377963457, 384.72684394080204, 5111.15998240634, 5219.791208351666, 5247.323864828936, 5313.973430132849, 5445.537129344006, 5139.897202055957, 5181.274621552986, 5271.789128434858, 5273.523413444233, 5314.0, 4199.202151591203, 4239.8092085856215, 4568.885777855556, 4581.908361832761, 4585.426821647294, 4136.648384887289, 4325.913481730364, 4500.923752933546, 4617.792199351021, 4630.7265501986885, 3873.198962482445, 4213.768242746648, 4525.298578720303, 4551.928872363083, 4600.194965880974, 4008.83964641414, 4288.935665965013, 4542.704724192933, 4546.318052292149, 4626.352310676508, 3438.484227365915, 4376.971411718244, 4378.430803995492, 4382.649388048949, 4389.209942210401, 4317.589828966243, 4793.452844894788, 4917.60659147271, 5153.587712982952, 5202.205239192473, 4620.0, 4634.837780677693, 5079.492050794928, 5086.745662716887, 5096.311997880276, 4410.889727756799, 5538.972305138448, 5693.146028095819, 5715.3998830122655, 5770.653760774367, 3676.7242456815848, 4078.8980275493054, 4306.246406878815, 4344.717593356421, 4499.257622492285, 5511.858240802744, 5679.438154507502, 5797.37663960025, 5819.0, 5874.765009399619, 4081.5306239782212, 4361.815874411697, 4571.0, 4581.977090114528, 4585.9770701146945, 4146.854860079883, 4464.441944756905, 4660.487346391894, 4669.2996050592, 4702.811887524529, 4091.4067460218102, 4364.738115713068, 4731.597814185764, 4978.5270399312, 5016.573591244785, 4836.613070954315, 5204.0, 5363.946360536344, 5436.026036176933, 5572.554195664418, 1482.8887833412537, 1557.509384543865, 1569.6344180562983, 1592.7258193445198, 1605.5825485373887, 1089.226649079147, 1159.5535718748174, 1177.7055736065984, 1199.7540504196618, 1201.3961361582385, 4442.400275962768, 4675.779004972679, 4909.789116767292, 4995.0259699359, 5159.329287192641, 4804.399450068741, 4924.96575719103, 5147.279380886679, 5258.97370513145, 5803.738831752581, 4500.572445617661, 4862.562369386701, 4964.801407943714, 5031.641456806642, 5161.4322424533275, 2559.590465525544, 2625.4617803350266, 2894.261963199413, 3014.5478178273434, 3069.956214886949, 5342.786288548492, 5387.515123638891, 5836.591438599342, 6064.089237166185, 6130.693465326716, 4791.688540244873, 5379.740508986235, 5692.815780401402, 5752.36723960364, 5952.523798096142, 5065.0116431305705, 5266.2890509781455, 5986.55100867428, 6022.819315420545, 6037.366076561898, 4568.611668008257, 5721.828345149652, 5786.884262314814, 5882.764619429968, 5914.467697907209, 4937.716418581746, 5210.713410762444, 5253.343332083489, 5687.876289689942, 5752.338481074563, 4994.1260279450225, 5407.729183641763, 5526.22632831412, 5709.886572118397, 6073.45126992614, 4562.315652651996, 4572.88567785808, 4595.471520775153, 4596.908061838812, 4614.66174809315, 5123.567209969255, 5259.789608415621, 5339.745159887501, 5773.566982476415, 5867.061270196748, 5066.138756411493, 5285.656432331886, 5621.8594535136945, 5632.915506267327, 5656.60403771732, 5199.532042116228, 5202.453742357149, 5359.088954877602, 5605.215269862304, 5638.4925356718095, 4790.568848803485, 5192.45479224676, 5464.3442786865835, 5504.559635229092, 5511.448855114457, 5013.044912484062, 5501.771782428123, 5556.526297396503, 5602.635828671123, 5662.603617746719, 4782.52174782519, 5133.716570857286, 5487.890242195054, 5498.3127109111365, 5501.707098831691], "value": 4275.677859190725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.3790519954047, 743.4201322968083, 764.0792844622231, 819.4018366592386, 829.3531045784287, 15148.091114533121, 16032.198390080508, 16068.839311606895, 16117.83882161178, 16144.83855161448, 15676.764848527282, 16668.08325542095, 17001.0, 17151.82848171518, 17322.913385433054, 894.6823877523483, 932.1796818799447, 952.4856577448204, 972.2756546372926, 972.9978122533767, 899.2715900120891, 955.0974329258853, 958.1903291718521, 969.839976403896, 985.6156099121348, 992.3847214726843, 1005.0703099632847, 1008.9646862359782, 1009.106940357785, 1009.1119814563211, 979.7011911367031, 997.1723469520281, 1002.0, 1002.6240159940022, 1003.4531180506605, 749.232037161908, 749.8275396658745, 751.2112282103769, 751.3838652305112, 752.0636807175048, 15901.840981590267, 16228.188590570424, 16269.267882945296, 16822.747658785127, 17081.743773843402, 14494.202818844959, 14553.199574023425, 14659.926700366537, 14665.193414362206, 14821.0, 11539.48072336747, 11718.414079296, 11768.58809941648, 11779.941100294529, 12292.323922184276, 3715.3498137825773, 3715.4612581175847, 3756.940068569612, 3759.0978165240417, 3775.735698501106, 90484.0, 92377.538112309, 114066.0, 115678.0, 121358.78641213563, 11368.261063030877, 11368.772624547628, 11393.259438136494, 11459.77080458386, 11782.0, 20820.37538873836, 23545.29364119079, 24394.75605243925, 24426.633600496007, 24441.87779061129, 7768.316515773954, 7869.1737367576725, 7877.7636670899965, 8018.639161237758, 8082.353411727047, 4260.659147268211, 4266.658667306608, 4354.608085272341, 4420.535843736361, 4529.6376289896725, 3542.309249696336, 3608.2061946371755, 3618.185908170693, 3621.5292012038267, 3622.185008373095, 536.6994483089483, 623.4015345268593, 630.2059405149453, 637.4708991537013, 654.3554598720257, 659.9901001484982, 669.7990602819232, 689.7447944260656, 693.1405057728381, 711.9323664251888, 9780.364276322016, 10111.3427627204, 10178.389296642225, 10595.417252051211, 11023.61417350389, 571.1860598646995, 576.7231728770214, 589.917411562373, 629.9055141728777, 682.1711620381261, 4364.890877728082, 4981.526754958273, 4990.426100998359, 5023.824166154167, 5046.899062018739, 83173.58413207895, 84294.20676069592, 87162.56418717866, 100864.0, 101868.98131018743, 89762.0, 90978.54510727404, 91184.54407727919, 92339.53830230806, 92454.0, 78373.60813195897, 79018.41963160704, 79980.60009700031, 80568.59715701384, 81815.0, 9638.987187613728, 9696.0788725071, 9715.56279967389, 9721.562529686322, 9772.657956971472, 45730.0, 49354.75322623364, 50110.749446252536, 51446.742766285926, 52121.95756084933, 64279.67860160669, 65527.672361639794, 68727.31272687209, 69208.30791692018, 73405.0, 12701.745965080461, 12856.678583035498, 13073.803892941423, 13434.462621495422, 13727.45090196381, 14486.927565362106, 14642.780358294422, 15260.771088433461, 15272.770908436605, 15728.370865165723, 11905.166638335233, 13478.595642130751, 13711.0, 14053.718925621635, 14649.92675036618, 10947.616833410792, 13346.332683365987, 13990.650233744238, 13994.16035037901, 13994.16035037901, 13042.739145216852, 13634.181949083088, 13733.725325493633, 13749.175049497062, 13756.449742010207, 9877.802443951225, 9996.60013599477, 10080.395176289447, 10150.898491014996, 10541.420221887869, 11026.0, 11205.60780372683, 11396.601118961129, 11484.540618375171, 11517.366544839779], "value": 7703.390884105104, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 120.0, 120.0, 119.0, 120.0, 124.0, 121.0, 121.0, 123.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 112.0, 146.0, 110.0, 115.0, 109.0, 114.0, 130.0, 116.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 208.0, 197.0, 204.0, 200.0, 200.0, 203.0, 189.0, 205.0, 201.0], "value": 200.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 64.0, 89.0, 62.0, 63.0, 62.0, 62.0, 78.0, 61.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 72.0, 71.0, 71.0, 83.0, 96.0, 101.0, 112.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 89.0, 90.0, 88.0, 89.0, 90.0, 89.0, 89.0, 89.0, 91.0], "value": 90.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 125.0, 153.0, 97.0, 97.0, 96.0, 97.0, 96.0], "value": 105.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 97.0, 73.0, 146.0, 149.0, 91.0, 91.0, 106.0, 92.0, 90.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 65.0, 68.0, 68.0, 69.0, 68.0, 68.0, 65.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 83.0, 89.0, 57.0, 80.0, 88.0, 84.0, 83.0, 50.0, 76.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 98.0, 98.0, 85.0, 84.0, 84.0, 87.0, 88.0, 81.0, 89.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 156.0, 149.0, 162.0, 149.0, 150.0, 175.0, 184.0, 152.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 158.0, 228.0, 204.0, 166.0, 169.0, 172.0, 181.0, 148.0, 193.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 68.0, 67.0, 65.0, 66.0, 68.0, 69.0], "value": 67.1, "unit": "score"}]}]} 13:30:42 INFO - Return code: 0 13:30:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:30:42 ERROR - # TBPL SUCCESS # 13:30:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:30:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:30:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:30:42 INFO - 2017-04-01 13:30:42.453 screenresolution[6931:139250] starting screenresolution argv=screenresolution get 13:30:42 INFO - 2017-04-01 13:30:42.455 screenresolution[6931:139250] Display 0: 1600x1200x32@60 13:30:42 INFO - 2017-04-01 13:30:42.462 screenresolution[6932:139253] starting screenresolution argv=screenresolution list 13:30:42 INFO - Available Modes on Display 0 13:30:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:30:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:30:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:30:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:30:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:30:42 INFO - Intel Iris: 13:30:42 INFO - Chipset Model: Intel Iris 13:30:42 INFO - Type: GPU 13:30:42 INFO - Bus: Built-In 13:30:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:30:42 INFO - Vendor: Intel (0x8086) 13:30:42 INFO - Device ID: 0x0a2e 13:30:42 INFO - Revision ID: 0x0009 13:30:42 INFO - Displays: 13:30:42 INFO - Display: 13:30:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:30:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:30:42 INFO - Display Serial Number: 1600x1200 60 13:30:42 INFO - Main Display: Yes 13:30:42 INFO - Mirror: Off 13:30:42 INFO - Online: Yes 13:30:42 INFO - Rotation: Supported 13:30:42 INFO - Return code: 0 13:30:42 INFO - Running post-action listener: _resource_record_post_action 13:30:42 INFO - [mozharness: 2017-04-01 20:30:42.709839Z] Finished run-tests step (success) 13:30:42 INFO - Running post-run listener: _resource_record_post_run 13:30:42 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 147968; Write bytes: 511236096; Read time: 4; Write time: 1619 13:30:42 INFO - TinderboxPrint: CPU usage
54.8% 13:30:42 INFO - TinderboxPrint: I/O read bytes / time
147,968 / 4 13:30:42 INFO - TinderboxPrint: I/O write bytes / time
511,236,096 / 1,619 13:30:42 INFO - TinderboxPrint: CPU idle
2,168.3 (45.2%) 13:30:42 INFO - TinderboxPrint: CPU system
972.4 (20.3%) 13:30:42 INFO - TinderboxPrint: CPU user
1,657.1 (34.5%) 13:30:42 INFO - TinderboxPrint: Swap in / out
207,810,560 / 0 13:30:42 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201394688; Write bytes: 201021440; Read time: 15938; Write time: 444 13:30:42 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 147456; Write bytes: 300302336; Read time: 3; Write time: 1145 13:30:43 INFO - Running post-run listener: _upload_blobber_files 13:30:43 INFO - Blob upload gear active. 13:30:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:30:43 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 13:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:30:43 INFO - (blobuploader) - INFO - Open directory for files ... 13:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:44 INFO - (blobuploader) - INFO - Done attempting. 13:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:30:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:45 INFO - (blobuploader) - INFO - Done attempting. 13:30:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:30:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:30:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:46 INFO - (blobuploader) - INFO - Done attempting. 13:30:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:30:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:30:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:30:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:30:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:30:48 INFO - (blobuploader) - INFO - Done attempting. 13:30:48 INFO - (blobuploader) - INFO - Iteration through files over. 13:30:48 INFO - Return code: 0 13:30:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:30:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:30:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6852551c146e83624e7e64045079033ac3e078598ef8cd13d699ddd67239e2fec363d70c438ba603f569e89bb2c0517e20988d0c0792b4f45aa7ce0090f1406f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4d3ca55a2c3bbf704d3c7dc1397c39e097a212b20f118312f3964b4e8daecce7b6fcf3e27807cd5947c2ac434ad5f816cf9fcf2be94bff20da4b3fba4984fce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81048bf04e3efb70252f042c178e45115854df67f426b2e199ee3b873f2e42bf74c7e64dff908bd00d9653d5e450cbb7c7b3863c4e40d5dd3348304b9a9b110d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2a75d0487c93229e4894feb07925080b75bd46960ac638d0c104f5d1429951a7cd500a857ff908cfee2cbe5f788d9b6e0efb3faf200ead784c9baed761453e9"} 13:30:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:30:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:30:48 INFO - Contents: 13:30:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6852551c146e83624e7e64045079033ac3e078598ef8cd13d699ddd67239e2fec363d70c438ba603f569e89bb2c0517e20988d0c0792b4f45aa7ce0090f1406f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4d3ca55a2c3bbf704d3c7dc1397c39e097a212b20f118312f3964b4e8daecce7b6fcf3e27807cd5947c2ac434ad5f816cf9fcf2be94bff20da4b3fba4984fce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81048bf04e3efb70252f042c178e45115854df67f426b2e199ee3b873f2e42bf74c7e64dff908bd00d9653d5e450cbb7c7b3863c4e40d5dd3348304b9a9b110d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2a75d0487c93229e4894feb07925080b75bd46960ac638d0c104f5d1429951a7cd500a857ff908cfee2cbe5f788d9b6e0efb3faf200ead784c9baed761453e9"} 13:30:48 INFO - Running post-run listener: copy_logs_to_upload_dir 13:30:48 INFO - Copying logs to upload dir... 13:30:48 INFO - mkdir: /builds/slave/test/build/upload/logs 13:30:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.784161 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-01 13:30:48.068019) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 13:30:48.071677) ========= 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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6852551c146e83624e7e64045079033ac3e078598ef8cd13d699ddd67239e2fec363d70c438ba603f569e89bb2c0517e20988d0c0792b4f45aa7ce0090f1406f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4d3ca55a2c3bbf704d3c7dc1397c39e097a212b20f118312f3964b4e8daecce7b6fcf3e27807cd5947c2ac434ad5f816cf9fcf2be94bff20da4b3fba4984fce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81048bf04e3efb70252f042c178e45115854df67f426b2e199ee3b873f2e42bf74c7e64dff908bd00d9653d5e450cbb7c7b3863c4e40d5dd3348304b9a9b110d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2a75d0487c93229e4894feb07925080b75bd46960ac638d0c104f5d1429951a7cd500a857ff908cfee2cbe5f788d9b6e0efb3faf200ead784c9baed761453e9"} build_url:https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011486 build_url: 'https://queue.taskcluster.net/v1/task/JRfWKKC8S2CGf-mNkTZ4LQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6852551c146e83624e7e64045079033ac3e078598ef8cd13d699ddd67239e2fec363d70c438ba603f569e89bb2c0517e20988d0c0792b4f45aa7ce0090f1406f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4d3ca55a2c3bbf704d3c7dc1397c39e097a212b20f118312f3964b4e8daecce7b6fcf3e27807cd5947c2ac434ad5f816cf9fcf2be94bff20da4b3fba4984fce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/81048bf04e3efb70252f042c178e45115854df67f426b2e199ee3b873f2e42bf74c7e64dff908bd00d9653d5e450cbb7c7b3863c4e40d5dd3348304b9a9b110d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2a75d0487c93229e4894feb07925080b75bd46960ac638d0c104f5d1429951a7cd500a857ff908cfee2cbe5f788d9b6e0efb3faf200ead784c9baed761453e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-01 13:30:48.107491) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:30:48.107865) ========= 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.tAdp8l8vWN/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.ZwXFA2FSlN/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.005165 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-01 13:30:48.160387) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 13:30:48.161168) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-01 13:30:48.161915) ========= ========= Total master_lag: 0.10 =========