builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0219 starttime: 1490736782.15 results: success (0) buildid: 20170328131621 builduid: 361be3a54f3f475ab307b50abd3073dc revision: e23cf1b38ad4b55416318d205864195d3666b4f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.151504) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.152039) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.152376) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.179814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.180203) ========= 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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-28 14:33:02-- 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% 151M=0s 2017-03-28 14:33:02 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124282 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.328840) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.329213) ========= 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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.027618 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:02.378340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 14:33:02.378702) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e23cf1b38ad4b55416318d205864195d3666b4f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e23cf1b38ad4b55416318d205864195d3666b4f3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-28 14:33:02,486 truncating revision to first 12 chars 2017-03-28 14:33:02,487 Setting DEBUG logging. 2017-03-28 14:33:02,487 attempt 1/10 2017-03-28 14:33:02,487 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e23cf1b38ad4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 14:33:03,968 unpacking tar archive at: mozilla-central-e23cf1b38ad4/testing/mozharness/ program finished with exit code 0 elapsedTime=2.101789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-28 14:33:04.504141) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:04.504598) ========= script_repo_revision: e23cf1b38ad4b55416318d205864195d3666b4f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:04.505008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:04.505373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 14:33:04.525546) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-28 14:33:04.526040) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:33:04 INFO - MultiFileLogger online at 20170328 14:33:04 in /builds/slave/test 14:33:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:33:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:33:04 INFO - {'append_to_log': False, 14:33:04 INFO - 'base_work_dir': '/builds/slave/test', 14:33:04 INFO - 'blob_upload_branch': 'Firefox', 14:33:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:33:04 INFO - 'branch': 'Firefox', 14:33:04 INFO - 'buildbot_json_path': 'buildprops.json', 14:33:04 INFO - 'config_files': ('talos/mac_config.py',), 14:33:04 INFO - 'default_actions': ('clobber', 14:33:04 INFO - 'read-buildbot-config', 14:33:04 INFO - 'download-and-extract', 14:33:04 INFO - 'populate-webroot', 14:33:04 INFO - 'create-virtualenv', 14:33:04 INFO - 'install', 14:33:04 INFO - 'run-tests'), 14:33:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:33:04 INFO - 'download_minidump_stackwalk': True, 14:33:04 INFO - 'download_symbols': 'ondemand', 14:33:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:33:04 INFO - 'tooltool.py': '/tools/tooltool.py', 14:33:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:33:04 INFO - '/tools/misc-python/virtualenv.py')}, 14:33:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:33:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:33:04 INFO - 'gecko_profile': False, 14:33:04 INFO - 'gecko_profile_interval': 0, 14:33:04 INFO - 'installer_path': 'installer.exe', 14:33:04 INFO - 'log_level': 'info', 14:33:04 INFO - 'log_name': 'talos', 14:33:04 INFO - 'log_to_console': True, 14:33:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:33:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:33:04 INFO - 'opt_config_files': (), 14:33:04 INFO - 'pip_index': False, 14:33:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:04 INFO - 'cmd': ('bash', 14:33:04 INFO - '-c', 14:33:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:04 INFO - 'enabled': True, 14:33:04 INFO - 'halt_on_failure': False, 14:33:04 INFO - 'name': 'check_screen_resolution'},), 14:33:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:04 INFO - 'cmd': ('bash', 14:33:04 INFO - '-c', 14:33:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:04 INFO - 'enabled': True, 14:33:04 INFO - 'halt_on_failure': False, 14:33:04 INFO - 'name': 'check_screen_resolution'},), 14:33:04 INFO - 'run_cmd_checks_enabled': True, 14:33:04 INFO - 'suite': 'dromaeojs-e10s', 14:33:04 INFO - 'system_bits': '32', 14:33:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:33:04 INFO - 'title': 't-yosemite-r7-0219', 14:33:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:33:04 INFO - 'use_talos_json': True, 14:33:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:33:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:33:04 INFO - 'work_dir': 'build'} 14:33:04 INFO - [mozharness: 2017-03-28 21:33:04.740588Z] Running clobber step. 14:33:04 INFO - Running pre-action listener: _resource_record_pre_action 14:33:04 INFO - Running main action method: clobber 14:33:04 INFO - rmtree: /builds/slave/test/build 14:33:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:33:07 INFO - Running post-action listener: _resource_record_post_action 14:33:07 INFO - [mozharness: 2017-03-28 21:33:07.126001Z] Finished clobber step (success) 14:33:07 INFO - [mozharness: 2017-03-28 21:33:07.126129Z] Running read-buildbot-config step. 14:33:07 INFO - Running pre-action listener: _resource_record_pre_action 14:33:07 INFO - Running main action method: read_buildbot_config 14:33:07 INFO - Using buildbot properties: 14:33:07 INFO - { 14:33:07 INFO - "project": "", 14:33:07 INFO - "product": "firefox", 14:33:07 INFO - "script_repo_revision": "production", 14:33:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:33:07 INFO - "repository": "", 14:33:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:33:07 INFO - "buildid": "20170328131621", 14:33:07 INFO - "pgo_build": "False", 14:33:07 INFO - "basedir": "/builds/slave/test", 14:33:07 INFO - "buildnumber": 113, 14:33:07 INFO - "slavename": "t-yosemite-r7-0219", 14:33:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:33:07 INFO - "platform": "yosemite_r7", 14:33:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:33:07 INFO - "branch": "mozilla-central", 14:33:07 INFO - "repo_path": "mozilla-central", 14:33:07 INFO - "revision": "e23cf1b38ad4b55416318d205864195d3666b4f3", 14:33:07 INFO - "stage_platform": "macosx64", 14:33:07 INFO - "builduid": "361be3a54f3f475ab307b50abd3073dc", 14:33:07 INFO - "slavebuilddir": "test" 14:33:07 INFO - } 14:33:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:33:07 INFO - Running post-action listener: _resource_record_post_action 14:33:07 INFO - [mozharness: 2017-03-28 21:33:07.129599Z] Finished read-buildbot-config step (success) 14:33:07 INFO - [mozharness: 2017-03-28 21:33:07.129736Z] Running download-and-extract step. 14:33:07 INFO - Running pre-action listener: _resource_record_pre_action 14:33:07 INFO - Running main action method: download_and_extract 14:33:07 INFO - mkdir: /builds/slave/test/build/tests 14:33: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')]} 14:33:07 INFO - trying https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:33:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:33:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:33:08 INFO - Downloaded 1325 bytes. 14:33:08 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:33:08 INFO - Using the following test package requirements: 14:33:08 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:33:08 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:33:08 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:33:08 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:33:08 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'jsshell-mac.zip'], 14:33:08 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:33:08 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:33:08 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:33:08 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:33:08 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:33:08 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:33:08 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:33:08 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:33:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:33:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:33:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:33:09 INFO - Expected file size: 52503642 14:33:09 INFO - Obtained file size: 52503642 14:33:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:33:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:33:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:33:25 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:33:27 INFO - Expected file size: 52503642 14:33:27 INFO - Obtained file size: 52503642 14:33:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:33:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:33:43 INFO - Fetch https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:33:44 INFO - Expected file size: 14404171 14:33:44 INFO - Obtained file size: 14404171 14:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:44 INFO - trying https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:44 INFO - Downloading https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:33:46 INFO - Downloaded 75588452 bytes. 14:33:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:46 INFO - mkdir: /builds/slave/test/properties 14:33:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:46 INFO - Contents: 14:33:46 INFO - build_url:https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:33:47 INFO - Running post-action listener: _resource_record_post_action 14:33:47 INFO - Running post-action listener: set_extra_try_arguments 14:33:47 INFO - [mozharness: 2017-03-28 21:33:47.444225Z] Finished download-and-extract step (success) 14:33:47 INFO - [mozharness: 2017-03-28 21:33:47.444362Z] Running populate-webroot step. 14:33:47 INFO - Running pre-action listener: _resource_record_pre_action 14:33:47 INFO - Running main action method: populate_webroot 14:33:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:47 INFO - '--tppagecycles', 14:33:47 INFO - '7'], 14:33:47 INFO - 'tests': ['tsvgm']}}, 14:33:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['damp', 'tps']}, 14:33:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'tests': ['damp', 'tps']}, 14:33:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['dromaeo_dom']}, 14:33:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['basic_compositor_video']}, 14:33:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['a11yr', 14:33:47 INFO - 'ts_paint', 14:33:47 INFO - 'tpaint', 14:33:47 INFO - 'sessionrestore', 14:33:47 INFO - 'sessionrestore_no_auto_restore', 14:33:47 INFO - 'tabpaint']}, 14:33:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['tsvgx', 14:33:47 INFO - 'tsvgr_opacity', 14:33:47 INFO - 'tart', 14:33:47 INFO - 'tscrollx', 14:33:47 INFO - 'cart', 14:33:47 INFO - 'tsvg_static']}, 14:33:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:47 INFO - 'tsvgr_opacity', 14:33:47 INFO - 'tart', 14:33:47 INFO - 'tscrollx', 14:33:47 INFO - 'cart', 14:33:47 INFO - 'tsvg_static']}, 14:33:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'talos_options': ['--disable-e10s'], 14:33:47 INFO - 'tests': ['tp5o']}, 14:33:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:33:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'talos_options': ['--disable-e10s', 14:33:47 INFO - '--xperf_path', 14:33:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:47 INFO - 'tests': ['tp5n']}, 14:33:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:33:47 INFO - 'talos_options': ['--xperf_path', 14:33:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:47 INFO - 'tests': ['tp5n']}}, 14:33:47 INFO - 'talos.zip': {'path': '', 14:33:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:47 INFO - Running post-action listener: _resource_record_post_action 14:33:47 INFO - [mozharness: 2017-03-28 21:33:47.462947Z] Finished populate-webroot step (success) 14:33:47 INFO - [mozharness: 2017-03-28 21:33:47.463073Z] Running create-virtualenv step. 14:33:47 INFO - Running pre-action listener: _resource_record_pre_action 14:33:47 INFO - Running main action method: create_virtualenv 14:33:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:47 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 14:33:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:47 INFO - Using real prefix '/tools/python27' 14:33:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:51 INFO - Installing pip.................done. 14:33:51 INFO - Return code: 0 14:33:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:33:51 INFO - Reading from file tmpfile_stdout 14:33:51 INFO - Output received: 14:33:51 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) 14:33:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:51 INFO - 'HOME': '/Users/cltbld', 14:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:51 INFO - 'LOGNAME': 'cltbld', 14:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:51 INFO - 'MOZ_NO_REMOTE': '1', 14:33:51 INFO - 'NO_EM_RESTART': '1', 14:33:51 INFO - 'PAGER': '/bin/cat', 14:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:51 INFO - 'PWD': '/builds/slave/test', 14:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:51 INFO - 'SHELL': '/bin/bash', 14:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:33:51 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:51 INFO - 'USER': 'cltbld', 14:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:51 INFO - 'XPC_FLAGS': '0x0', 14:33:51 INFO - 'XPC_SERVICE_NAME': '0', 14:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:51 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 14:33:51 INFO - Cleaning up... 14:33:51 INFO - Return code: 0 14:33:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:51 INFO - 'HOME': '/Users/cltbld', 14:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:51 INFO - 'LOGNAME': 'cltbld', 14:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:51 INFO - 'MOZ_NO_REMOTE': '1', 14:33:51 INFO - 'NO_EM_RESTART': '1', 14:33:51 INFO - 'PAGER': '/bin/cat', 14:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:51 INFO - 'PWD': '/builds/slave/test', 14:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:51 INFO - 'SHELL': '/bin/bash', 14:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:33:51 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:51 INFO - 'USER': 'cltbld', 14:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:51 INFO - 'XPC_FLAGS': '0x0', 14:33:51 INFO - 'XPC_SERVICE_NAME': '0', 14:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:52 INFO - Downloading/unpacking psutil>=3.1.1 14:33:52 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 14:33:52 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 14:33:52 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 14:33:52 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 14:33:52 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 14:33:52 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 14:33:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:33:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:55 INFO - Installing collected packages: psutil 14:33:55 INFO - Running setup.py install for psutil 14:33:55 INFO - building 'psutil._psutil_osx' extension 14:33:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:33:56 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 14:33:56 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 14:33:56 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 14:33:56 INFO - building 'psutil._psutil_posix' extension 14:33:56 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 14:33:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:33:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:33:56 INFO - ^ 14:33:56 INFO - 1 warning generated. 14:33:56 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 14:33:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:56 INFO - Successfully installed psutil 14:33:56 INFO - Cleaning up... 14:33:56 INFO - Return code: 0 14:33:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:56 INFO - 'HOME': '/Users/cltbld', 14:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:56 INFO - 'LOGNAME': 'cltbld', 14:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:56 INFO - 'MOZ_NO_REMOTE': '1', 14:33:56 INFO - 'NO_EM_RESTART': '1', 14:33:56 INFO - 'PAGER': '/bin/cat', 14:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:56 INFO - 'PWD': '/builds/slave/test', 14:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:56 INFO - 'SHELL': '/bin/bash', 14:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:33:56 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:56 INFO - 'USER': 'cltbld', 14:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:56 INFO - 'XPC_FLAGS': '0x0', 14:33:56 INFO - 'XPC_SERVICE_NAME': '0', 14:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:33:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:33:59 INFO - Installing collected packages: mozsystemmonitor 14:33:59 INFO - Running setup.py install for mozsystemmonitor 14:34:00 INFO - Successfully installed mozsystemmonitor 14:34:00 INFO - Cleaning up... 14:34:00 INFO - Return code: 0 14:34:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:34:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:00 INFO - 'HOME': '/Users/cltbld', 14:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:00 INFO - 'LOGNAME': 'cltbld', 14:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:00 INFO - 'MOZ_NO_REMOTE': '1', 14:34:00 INFO - 'NO_EM_RESTART': '1', 14:34:00 INFO - 'PAGER': '/bin/cat', 14:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:00 INFO - 'PWD': '/builds/slave/test', 14:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:00 INFO - 'SHELL': '/bin/bash', 14:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:00 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:00 INFO - 'USER': 'cltbld', 14:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:00 INFO - 'XPC_FLAGS': '0x0', 14:34:00 INFO - 'XPC_SERVICE_NAME': '0', 14:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 INFO - Downloading/unpacking jsonschema==2.5.1 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:34:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - Downloading functools32-3.2.3-2.tar.gz 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:34:04 INFO - warning: no files found matching '*.txt' 14:34:04 INFO - no previously-included directories found matching 'build' 14:34:04 INFO - no previously-included directories found matching 'dist' 14:34:04 INFO - no previously-included directories found matching '.git*' 14:34:04 INFO - Installing collected packages: jsonschema, functools32 14:34:04 INFO - Running setup.py install for functools32 14:34:04 INFO - warning: no files found matching '*.txt' 14:34:04 INFO - no previously-included directories found matching 'build' 14:34:04 INFO - no previously-included directories found matching 'dist' 14:34:04 INFO - no previously-included directories found matching '.git*' 14:34:04 INFO - Successfully installed jsonschema functools32 14:34:04 INFO - Cleaning up... 14:34:04 INFO - Return code: 0 14:34:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:34:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:04 INFO - 'HOME': '/Users/cltbld', 14:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:04 INFO - 'LOGNAME': 'cltbld', 14:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:04 INFO - 'MOZ_NO_REMOTE': '1', 14:34:04 INFO - 'NO_EM_RESTART': '1', 14:34:04 INFO - 'PAGER': '/bin/cat', 14:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:04 INFO - 'PWD': '/builds/slave/test', 14:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:04 INFO - 'SHELL': '/bin/bash', 14:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:04 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:04 INFO - 'USER': 'cltbld', 14:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:04 INFO - 'XPC_FLAGS': '0x0', 14:34:04 INFO - 'XPC_SERVICE_NAME': '0', 14:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:34:05 INFO - Cleaning up... 14:34:05 INFO - Return code: 0 14:34:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:05 INFO - 'HOME': '/Users/cltbld', 14:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:05 INFO - 'LOGNAME': 'cltbld', 14:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:05 INFO - 'MOZ_NO_REMOTE': '1', 14:34:05 INFO - 'NO_EM_RESTART': '1', 14:34:05 INFO - 'PAGER': '/bin/cat', 14:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:05 INFO - 'PWD': '/builds/slave/test', 14:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:05 INFO - 'SHELL': '/bin/bash', 14:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:05 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:05 INFO - 'USER': 'cltbld', 14:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:05 INFO - 'XPC_FLAGS': '0x0', 14:34:05 INFO - 'XPC_SERVICE_NAME': '0', 14:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:05 INFO - Downloading/unpacking blobuploader==1.2.4 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:07 INFO - Downloading blobuploader-1.2.4.tar.gz 14:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:34:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:34:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:09 INFO - Downloading docopt-0.6.1.tar.gz 14:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:34:09 INFO - Installing collected packages: blobuploader, requests, docopt 14:34:09 INFO - Running setup.py install for blobuploader 14:34:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:34:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:34:09 INFO - Running setup.py install for requests 14:34:09 INFO - Running setup.py install for docopt 14:34:10 INFO - Successfully installed blobuploader requests docopt 14:34:10 INFO - Cleaning up... 14:34:10 INFO - Return code: 0 14:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:10 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')]} 14:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:10 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')]} 14:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:10 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 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:10 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 14:34:10 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 14:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:10 INFO - 'HOME': '/Users/cltbld', 14:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:10 INFO - 'LOGNAME': 'cltbld', 14:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:10 INFO - 'MOZ_NO_REMOTE': '1', 14:34:10 INFO - 'NO_EM_RESTART': '1', 14:34:10 INFO - 'PAGER': '/bin/cat', 14:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:10 INFO - 'PWD': '/builds/slave/test', 14:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:10 INFO - 'SHELL': '/bin/bash', 14:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:10 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:10 INFO - 'USER': 'cltbld', 14:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:10 INFO - 'XPC_FLAGS': '0x0', 14:34:10 INFO - 'XPC_SERVICE_NAME': '0', 14:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VL71t7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-11sz9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-KZ37NQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Qo3Txk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-F6EsUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:10 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-AKGynq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-uAORjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ZZY_1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-myCgF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ROgZJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-HvNySM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-_JNAYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-qsMwyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-FyILkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-XFE60q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:11 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IXZoA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:12 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5PL7co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:34:12 INFO - Running setup.py install for manifestparser 14:34:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozcrash 14:34:12 INFO - Running setup.py install for mozdebug 14:34:12 INFO - Running setup.py install for mozdevice 14:34:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:34:12 INFO - Running setup.py install for mozfile 14:34:12 INFO - Running setup.py install for mozhttpd 14:34:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozinfo 14:34:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozInstall 14:34:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozleak 14:34:13 INFO - Running setup.py install for mozlog 14:34:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for moznetwork 14:34:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:34:13 INFO - Running setup.py install for mozprocess 14:34:13 INFO - Running setup.py install for mozprofile 14:34:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for mozrunner 14:34:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Running setup.py install for mozscreenshot 14:34:14 INFO - Running setup.py install for moztest 14:34:14 INFO - Running setup.py install for mozversion 14:34:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:14 INFO - Cleaning up... 14:34:14 INFO - Return code: 0 14:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34: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')]} 14:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:34:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:14 INFO - 'HOME': '/Users/cltbld', 14:34:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:14 INFO - 'LOGNAME': 'cltbld', 14:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:14 INFO - 'MOZ_NO_REMOTE': '1', 14:34:14 INFO - 'NO_EM_RESTART': '1', 14:34:14 INFO - 'PAGER': '/bin/cat', 14:34:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:14 INFO - 'PWD': '/builds/slave/test', 14:34:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:14 INFO - 'SHELL': '/bin/bash', 14:34:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:14 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:14 INFO - 'USER': 'cltbld', 14:34:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:14 INFO - 'XPC_FLAGS': '0x0', 14:34:14 INFO - 'XPC_SERVICE_NAME': '0', 14:34:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-GveMSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:15 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)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dapGfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:15 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)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-KAez_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:15 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)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-7f98rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:15 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)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-KBCZhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:15 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)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-PupZof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ofsRk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-J0vLOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dRmuJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:15 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-BEZNn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:16 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)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jpueDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:16 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)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-idkjhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:16 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)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-SKqV5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:16 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)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5Tz7zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:16 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)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-xwQ8__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-L6TTqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:16 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-PtpVkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:34:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:19 INFO - Downloading blessings-1.6.tar.gz 14:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:19 INFO - Installing collected packages: blessings 14:34:19 INFO - Running setup.py install for blessings 14:34:19 INFO - Successfully installed blessings 14:34:19 INFO - Cleaning up... 14:34:19 INFO - Return code: 0 14:34:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Current package versions: 14:34:20 INFO - blessings == 1.6 14:34:20 INFO - blobuploader == 1.2.4 14:34:20 INFO - docopt == 0.6.1 14:34:20 INFO - functools32 == 3.2.3-2 14:34:20 INFO - jsonschema == 2.5.1 14:34:20 INFO - manifestparser == 1.1 14:34:20 INFO - mozInstall == 1.12 14:34:20 INFO - mozcrash == 1.0 14:34:20 INFO - mozdebug == 0.1 14:34:20 INFO - mozdevice == 0.49 14:34:20 INFO - mozfile == 1.2 14:34:20 INFO - mozhttpd == 0.7 14:34:20 INFO - mozinfo == 0.9 14:34:20 INFO - mozleak == 0.1 14:34:20 INFO - mozlog == 3.4 14:34:20 INFO - moznetwork == 0.27 14:34:20 INFO - mozprocess == 0.25 14:34:20 INFO - mozprofile == 0.28 14:34:20 INFO - mozrunner == 6.13 14:34:20 INFO - mozscreenshot == 0.1 14:34:20 INFO - mozsystemmonitor == 0.3 14:34:20 INFO - moztest == 0.8 14:34:20 INFO - mozversion == 1.4 14:34:20 INFO - psutil == 3.1.1 14:34:20 INFO - requests == 1.2.3 14:34:20 INFO - wsgiref == 0.1.2 14:34:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:20 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')]} 14:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:20 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')]} 14:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:20 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 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:20 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 14:34:20 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 14:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:20 INFO - 'HOME': '/Users/cltbld', 14:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:20 INFO - 'LOGNAME': 'cltbld', 14:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:20 INFO - 'MOZ_NO_REMOTE': '1', 14:34:20 INFO - 'NO_EM_RESTART': '1', 14:34:20 INFO - 'PAGER': '/bin/cat', 14:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:20 INFO - 'PWD': '/builds/slave/test', 14:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:20 INFO - 'SHELL': '/bin/bash', 14:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:20 INFO - 'USER': 'cltbld', 14:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:20 INFO - 'XPC_FLAGS': '0x0', 14:34:20 INFO - 'XPC_SERVICE_NAME': '0', 14:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 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)) 14:34:20 INFO - Cleaning up... 14:34:20 INFO - Return code: 0 14:34:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34:20 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')]} 14:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:20 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')]} 14:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:34:20 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 0x107a66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4f2caee90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, '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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 14:34:20 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 14:34:20 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 14:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:20 INFO - 'HOME': '/Users/cltbld', 14:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:20 INFO - 'LOGNAME': 'cltbld', 14:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:20 INFO - 'MOZ_NO_REMOTE': '1', 14:34:20 INFO - 'NO_EM_RESTART': '1', 14:34:20 INFO - 'PAGER': '/bin/cat', 14:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:20 INFO - 'PWD': '/builds/slave/test', 14:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:20 INFO - 'SHELL': '/bin/bash', 14:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:20 INFO - 'USER': 'cltbld', 14:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:20 INFO - 'XPC_FLAGS': '0x0', 14:34:20 INFO - 'XPC_SERVICE_NAME': '0', 14:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:34:20 INFO - Cleaning up... 14:34:20 INFO - Return code: 0 14:34:20 INFO - Running post-action listener: _resource_record_post_action 14:34:20 INFO - Running post-action listener: _start_resource_monitoring 14:34:20 INFO - Starting resource monitoring. 14:34:20 INFO - [mozharness: 2017-03-28 21:34:20.669071Z] Finished create-virtualenv step (success) 14:34:20 INFO - [mozharness: 2017-03-28 21:34:20.669999Z] Running install step. 14:34:20 INFO - Running pre-action listener: _resource_record_pre_action 14:34:20 INFO - Running main action method: install 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:20 INFO - Reading from file tmpfile_stdout 14:34:20 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:21 INFO - Reading from file tmpfile_stdout 14:34:21 INFO - Output received: 14:34:21 INFO - Usage: mozinstall [options] installer 14:34:21 INFO - Options: 14:34:21 INFO - -h, --help show this help message and exit 14:34:21 INFO - -d DEST, --destination=DEST 14:34:21 INFO - Directory to install application into. [default: 14:34:21 INFO - "/builds/slave/test"] 14:34:21 INFO - --app=APP Application being installed. [default: firefox] 14:34:21 INFO - mkdir: /builds/slave/test/build/application 14:34:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:34:41 INFO - Reading from file tmpfile_stdout 14:34:41 INFO - Output received: 14:34:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:34:41 INFO - Running post-action listener: _resource_record_post_action 14:34:41 INFO - [mozharness: 2017-03-28 21:34:41.152899Z] Finished install step (success) 14:34:41 INFO - [mozharness: 2017-03-28 21:34:41.153055Z] Running run-tests step. 14:34:41 INFO - Running pre-action listener: _resource_record_pre_action 14:34:41 INFO - Running main action method: run_tests 14:34:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:34:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:34:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:34:41 INFO - 2017-03-28 14:34:41.167 screenresolution[1621:7300] starting screenresolution argv=screenresolution get 14:34:41 INFO - 2017-03-28 14:34:41.170 screenresolution[1621:7300] Display 0: 1600x1200x32@60 14:34:41 INFO - 2017-03-28 14:34:41.175 screenresolution[1622:7303] starting screenresolution argv=screenresolution list 14:34:41 INFO - Available Modes on Display 0 14:34:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:34:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:34:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:34:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:34:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:34:41 INFO - Intel Iris: 14:34:41 INFO - Chipset Model: Intel Iris 14:34:41 INFO - Type: GPU 14:34:41 INFO - Bus: Built-In 14:34:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:34:41 INFO - Vendor: Intel (0x8086) 14:34:41 INFO - Device ID: 0x0a2e 14:34:41 INFO - Revision ID: 0x0009 14:34:41 INFO - Displays: 14:34:41 INFO - Display: 14:34:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:34:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:34:41 INFO - Display Serial Number: 1600x1200 60 14:34:41 INFO - Main Display: Yes 14:34:41 INFO - Mirror: Off 14:34:41 INFO - Online: Yes 14:34:41 INFO - Rotation: Supported 14:34:41 INFO - Return code: 0 14:34:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:41 INFO - Python 2.7.3 14:34:41 INFO - Return code: 0 14:34:41 INFO - grabbing minidump binary from tooltool 14:34: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')]} 14:34:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107844d30>, '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 0x10776bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107839be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:34:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:34:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:34:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:41 INFO - Return code: 0 14:34:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:34:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:41 INFO - ENV: RUST_BACKTRACE is now 1 14:34:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:34:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 14:34:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --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 14:34:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZzdcNk6CuT/Render', 14:34:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:41 INFO - 'HOME': '/Users/cltbld', 14:34:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:41 INFO - 'LOGNAME': 'cltbld', 14:34:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:41 INFO - 'MOZ_NO_REMOTE': '1', 14:34:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:41 INFO - 'NO_EM_RESTART': '1', 14:34:41 INFO - 'PAGER': '/bin/cat', 14:34:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:41 INFO - 'PWD': '/builds/slave/test', 14:34:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:41 INFO - 'RUST_BACKTRACE': '1', 14:34:41 INFO - 'SHELL': '/bin/bash', 14:34:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X1DWpGQfAg/Listeners', 14:34:41 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 14:34:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:41 INFO - 'USER': 'cltbld', 14:34:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:41 INFO - 'XPC_FLAGS': '0x0', 14:34:41 INFO - 'XPC_SERVICE_NAME': '0', 14:34:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 14:34:41 INFO - mozversion application_buildid: 20170328131621 14:34:41 INFO - mozversion application_changeset: e23cf1b38ad4b55416318d205864195d3666b4f3 14:34:41 INFO - mozversion application_display_name: Nightly 14:34:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:41 INFO - mozversion application_name: Firefox 14:34:41 INFO - mozversion application_remotingname: firefox 14:34:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:34:41 INFO - mozversion application_vendor: Mozilla 14:34:41 INFO - mozversion application_version: 55.0a1 14:34:41 INFO - mozversion platform_buildid: 20170328131621 14:34:41 INFO - mozversion platform_changeset: e23cf1b38ad4b55416318d205864195d3666b4f3 14:34:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:34:41 INFO - mozversion platform_version: 55.0a1 14:34:41 INFO - using testdate: 1490736881 14:34:41 INFO - actual date: 1490736881 14:34:41 INFO - starting webserver on 'localhost:49236' 14:34:41 INFO - SUITE-START | Running 2 tests 14:34:41 INFO - TEST-START | dromaeo_css 14:34:41 INFO - operating with platform_type : mac_ 14:34:41 INFO - Initialising browser for dromaeo_css test... 14:34:41 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile http://localhost:49236/getInfo.html) 14:34:44 INFO - PID 1632 | 2017-03-28 14:34:44.988 plugin-container[1634:7482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 14:34:44 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:44 INFO - PID 1632 | 2017-03-28 14:34:44.991 plugin-container[1634:7482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 14:34:44 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:44 INFO - PID 1632 | 2017-03-28 14:34:44.991 plugin-container[1634:7482] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:34:45 INFO - PID 1632 | __metrics Screen width/height:1600/1200 14:34:45 INFO - PID 1632 | colorDepth:24 14:34:45 INFO - PID 1632 | Browser inner width/height: 1024/689 14:34:45 INFO - PID 1632 | __metrics 14:34:45 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 14:34:45 ERROR - PID 1632 | 1490736885227 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:34:45 INFO - TEST-INFO | 1632: exit 0 14:34:45 INFO - Browser initialized. 14:34:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:45 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpegB_Xw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:48 INFO - PID 1635 | 2017-03-28 14:34:48.339 plugin-container[1636:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:34:48 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:48 INFO - PID 1635 | 2017-03-28 14:34:48.340 plugin-container[1636:7613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 14:34:48 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:48 INFO - PID 1635 | 2017-03-28 14:34:48.340 plugin-container[1636:7613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:34:58 INFO - PID 1635 | RSS: Main: 229576704 14:34:58 INFO - PID 1635 | 14:38:02 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 14:38:04 INFO - PID 1635 | RSS: Main: 252559360 14:38:04 INFO - PID 1635 | 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 14:39:46 INFO - PID 1635 | 14:39:46 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:46 INFO - PID 1635 | Full stack: 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 14:39:46 INFO - PID 1635 | 14:39:46 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:46 INFO - PID 1635 | Full stack: 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 14:39:46 INFO - PID 1635 | 14:39:46 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:46 INFO - PID 1635 | Full stack: 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | ************************* 14:39:46 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 14:39:46 INFO - PID 1635 | 14:39:46 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:39:46 INFO - PID 1635 | Full stack: 14:39:46 INFO - PID 1635 | ************************* 14:41:06 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 14:41:07 INFO - PID 1635 | RSS: Main: 260169728 14:41:07 INFO - PID 1635 | 14:44:08 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 14:44:09 INFO - PID 1635 | RSS: Main: 272572416 14:44:09 INFO - PID 1635 | 14:47:11 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 14:47:12 INFO - PID 1635 | RSS: Main: 264892416 14:47:12 INFO - PID 1635 | 14:50:15 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 14:50:15 INFO - PID 1635 | RSS: Main: 275140608 14:50:15 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 14:53:18 INFO - PID 1635 | RSS: Main: 269012992 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | __start_tp_report 14:53:18 INFO - PID 1635 | _x_x_mozilla_page_load 14:53:18 INFO - PID 1635 | _x_x_mozilla_page_load_details 14:53:18 INFO - PID 1635 | |i|pagename|runs| 14:53:18 INFO - PID 1635 | |0;dojo.html;2904;2962.229820246736;3520.3135388599235;3553.893383198504;3578.731595130366;6119.694015299239;6172.413620706031;6175.289841668209;6237.126802247689;6239.188905442292;5117.974410127954;6137.928623859632;6167.828112658592;6185.319614842364;6194.752209911598;4571.1771881061395;4723.3859598252175;5596.916046259299;5647.802326918559;5720.713964301789;4574.382458368128;5601.411851755568;5645.915311270334;5716.657000579958;5746.454086861741;5618.0449323615085;5673.177389278573;5683.687397193153;5699.145128230757;5762.855928601776;4614.3770590970125;5452.863678408032;5530.917036244459;5564.27664403629;5597.384287728347;5562.443755624446;5572.637778544422;5575.1915972184215;5619.859503512404;5648.378678345379;5013.348264725599;5946.524278057745;6268.749250029993;6269.341719119472;6291.213598300213;6367.076773867763;6425.8393540161405;6564.5404821662505;6619.371159739817;6644;6761.729530818761;6771.830704232384;6777.288384719632;6782.76260330891;6794.184697836242;5460.645058071213;6244.687765611701;6293.527985401068;6303.432691057781;6309.242890853127;6645.833854153685;6702.296258892844;6837.316268373223;6861.382475577222;6927.3419025192525;6061.454469097803;6308.400701933309;6451.161349024596;6532.542722009509;6558;5511.779528818883;6520.445762110274;6530.640814755234;6546.967265163739;6558.213014438298;5116.616253780981;6547.639879806561;6561.475081993429;6636.502262330345;6637.568558043712;6547.377999090078;6633.568818026813;6674.699638516278;6689.832754181184;6692.163479565054;5065;5268.736563171826;5292.788288468495;5300.469953004668;5303.15149576066;4636.420447444069;4688.46082700487;4719.197736384823;4773.785179666854;4809.278608208727;6296.212973378328;6319.526035547352;6337.6197428154455;6345.682715864188;6346.460550853137;6739.460843132636;6772.492063095287;6804.08144900442;6816.965915170492;6832.624205668736;6146.692665366803;6260.029695397129;6277.8744425111245;6291.822629572128;6331.810045698637;5916.497097746654;5944.7324870380935;5963.940360596339;5975.223220981332;5991.700414979234;6870.965645171742;6943.236244013155;6984.476164287698;6998.860022799618;7008.22909479967;6474.805755827333;6533.248676402084;6690.665466726741;6713.530052896251;6837;6859.279775623687;6992.4406047517095;6995.580265184105;7004.614746189009;7031.1211098612675;6515.2833188348295;6611.239707433707;6624.768133115318;6628.5691430056895;6643.302453242533;6459.61242325462;6524.771632993013;6590.90113648286;6686.7993960181275;6788.796336109925;5460.481254280837;6362.904556431565;6401.775937842153;6469.385408386196;6493.253275873146;5307.761150748149;5374.489423504917;6139.093519790236;6488.772892948724;6490.902636460362;6158.969205153946;6300.243970723543;6347.365263473615;6354.301026886947;6386.297507274289;6217.378262173746;6439.967800160969;6461.321561236003;6475.7733479328;6504.9674751625935;5457.918131228114;5647.491725744702;6278.246610406781;6327.936720632735;6338.144350512806 14:53:18 INFO - PID 1635 | |1;ext.html;759.1004659478518;804.8390321935614;835.0939230934435;1285.5950375631676;1303.7588046211445;37611;38515.80742096293;38610;38701.41947870777;38706.4194037089;30820.537691934638;33741.4816333265;33757.64969401221;38953.41569876454;39416.80291598538;7941.841163176733;8318.41771076025;8459.4078414511;8652.178043085929;8711.0417854036;7268.927310726878;8442.746717598482;8600.311975042014;8702.956485217597;8705.825883482326;8625.266852317529;8637.611307491177;8697.608607612641;8709.695160669346;8739.563021848913;8655.17775811297;8689;8712;8732.257758090569;8774.473531588124;8639.65441382344;8701.912980870173;8720.651173953034;8723.38936274461;8735.126487351277;38659.613403865886;38802;38810;38840.80579597112;38863.805680971695;2516.3709072731817;3005.549167624874;3023.5313526403443;3051.4659934511374;3059.189314831571;27983.720162798316;27992.580111298346;28038;28232.294192645142;28297.71702282992;2805.1724741201397;2831.164806382122;2863.7708983281286;2865.498537755884;2866.2977570495154;347096;347198;348718;351194;351227;50656;50839;50913;50915.490845091816;50932.72668183325;51547;51849;52117.95764084697;52187.47812521826;52264.73867630638;14387.920905932097;14654.120752754869;14736.189509576861;14744.493436115887;14788.63028424298;11820.822687659693;11971.64085077449;11983.64049078529;11992.340421276738;11994.76010479803;13381.46474141043;13529.391177397041;13633.70479804417;13682;13777.966652501102;5388.461153884658;6029.668368239789;6071.817845464643;6093.329733729286;6117.877642447215;933.8972713001565;965.6089283840056;981.1365997921926;1007.8437842134481;1025.9230557708202;24371;24540.895659695;24759.87620061888;24778.380540486633;24800.627990579796;1102.3551222534747;1113.3876367997568;1135.9886401135882;1145.4272863568217;1151.6084531259412;11939.88060119405;12592.496300148076;12637.747245055047;12638.684032899251;12759.298238596966;484561;486808.56595716794;488522;489376;489761;51752.74123629358;56275;56451.87096258134;56736.716316418155;56784.864302713715;1142.4687520303098;1291.0768800307826;1499.482678475924;1500.3848422146868;1513.8940274180923;24055.639165412184;26956.730432696208;28116.297092572848;28488.287792805346;29041.854790726757;38204.9884762096;38329.23341533209;38362.04094897538;38472.19097612798;38476.42285365778;385527.0723646364;385754.0712296421;386737.19894201047;387285;387308.1269187272;30626.540601891436;31280.530792037684;31346.843265783526;31353;31382.901598443947;40019.79990100031;40494.9876253096;40859;40880.79559602183;40989.59010409858;28836.855815720788;28985.855070724512;29586.40827183401;29633.851830740707;29889.85055074798;28880;28913.42173156483;29475.115746527637;29649;29728.108156755334;33063.17342066371;33511.99464016083;33790.66209337875;34181.31637367288;34189;27518.312042199104;29154.854225728737;29534.852325738233;29869.402611948073;29946.95185668491;21066.226876229288;23148;23327.76794836638;23406.18078367249;23562.175323863583 14:53:18 INFO - PID 1635 | |2;jquery.html;377.4451556212368;430.30505733240824;434.4525897369314;441.28732097662265;457.0060119240651;18589.349372771954;20187.59624807505;20927.058282377293;20989;21009.474763130893;22259.332220033386;22268.331950041484;23328.883355583243;23379.298621041355;23434.64848027277;6112.816615501542;6341.429271365576;6357.745690172387;6365.045243213532;6367.235931688204;5944.554158438134;6191.0713392990965;6240.282367527724;6258.937410625904;6263.718132684017;6050.606710563799;6188.319284878659;6214.316425193225;6232.158658581102;6232.781852635158;6124.295705993801;6195.535334849904;6207;6243.125962365272;6247.5939063960695;5566.2207290979295;5575.135853942646;5602.523785478239;5604.103343465067;5615.298087739033;27079;27259.90960361583;27878.860605696842;28208.602268807175;28240.011599593916;1998.570307383905;2003.9899800500905;2014.617222727677;2019.5758890632985;2054.3323419888447;15754;16595.087270200132;16718.99686018843;16887.66224675512;16924.66150676992;1695.2710334556136;1700.7533907583331;1717;1719.1146559521856;1727.3954116059404;139594.30202849023;146430;146513.26743366214;147007.26496367555;147216;12483.56307529232;12588.937055314665;12611;12742.872571274354;12876.80684789729;16325.91837040831;16343.67312653764;16349.836501635069;16367.91816040912;16379.59051023734;8802.823943521094;9003.819923601492;9082.137196966278;9275.39709918853;9276.07239276067;8633.827323453495;8707.738767836974;8729.301655867515;8908.10918908104;8912.598933048028;7870.488418252795;8040.545576507462;8069.3544516439715;8094.635741391651;8139.877901831477;2095.9371218863457;2187.475005998581;2190.7371115465926;2191.3206905859233;2386.9880650596638;728.8287252495659;768.5772824946257;870.4081224767219;946.914777670013;948.4498990585444;15672.764908526382;15963;18627.81372186288;19881.502962425766;21159.25942592002;810.4448452809844;945.8486642137362;946.4463288976026;960.6973803251954;969.2294625772528;10270.435126067989;10780.78438431227;10792.298500597593;10898.237123401444;10925.289856159483;715035;716724;721516.0635542826;725186.3740681263;728816;161355;161961;162884;163947;164967;1171.3264872698232;1179.941002949849;1204.2172587817813;1205.2768338996707;1246.8067449545335;20469.79530204679;20818.791812081687;21022.68465972981;21040.68438973386;21061.578768424853;63999.04001439889;64044;64412;64988;65872.67063664651;88187;90387.54806225927;90795;92647;92891;12538.686532836751;12540.31028293416;12667.927156931111;12832.935835321137;12847.550335738204;13725;13753.418356888686;14044.368003439666;14164.150150990974;14181.929090354482;9039.593218305323;9647.855282170633;11782.764344712887;11843.348615826213;11844.822327664919;9119.042500537347;9811.803763924825;10727.463626818784;10914;11092.279001864854;10358.5338659762;11683.824742628698;11995.880041199825;12062.698432539257;12164.330961797186;12060.638180854588;12506.937465312616;12690.555830545887;12806.61580152597;12909.870901291242;11661.825072623747;11826.763464730486;11860.644180674593;11881.643550693492;12009.81985270239 14:53:18 INFO - PID 1635 | |3;mootools.html;1260.6281147061618;1344.193483909655;1383.958481245563;1406.3179358011362;1420.616433562938;9466.668666596663;10329.741756456084;10684.946575267133;10712.73218169546;10959.397233152173;9207.07929207079;9293.814123717522;10492.10817080549;10593.152547796177;10766.892331076688;2206.0403724379867;2329.8835058247105;2379.512199998996;2477.8884950177194;2481.1440053181623;2329.2546385156757;2407.1935901473025;2407.38611654028;2424.733279339271;2452.7792498675117;2392.1149174805346;2455.680761501011;2463.1379017343966;2465.5192237513693;2471.0733474946896;2051.2820512820545;2364.775346342095;2425.9757402426017;2438.90254381232;2446.804255659552;2367.4673198530363;2422.39440139965;2433.890474928615;2439.8048156147465;2464.9260522184363;10655.254132210748;10655.52050157737;10717.303375280582;10732.78534429307;10907.018368346888;6177.62934223948;6402.391772781578;6413.134226879358;6421.903671444932;6425.389587989133;3081.984590077035;3235.854386552611;3881.971277611435;3885.1064255221177;3999.98000010001;1092.4046394714899;1107.9612213572566;1123.5449642894641;1133.5805751871778;1168.9298642081503;13314;13727.245001524912;14834.777478337834;15040.774388384183;15094.54716358511;5264.684118952875;5383.300170977748;5399.541039011649;5418.349798024262;5443;11213.887861121448;11221.270617409842;11289.475920750594;11348.54605815758;11392.25950313227;6093.421124993119;6212.285587157535;6254.6872656366995;6260.812175634738;6269.937300627026;5810.244668193108;5885.735141918624;5954.851128721816;5956.910646340309;5999.970000149971;4117.732347397409;4301;4338.414314067623;4389.385486031958;4394.055278115221;1095.3756358875364;1099.9560017599367;1101.2566517600587;1114.5931734916696;1117.234694234452;741.9109706835106;754.7433872483272;771.1132197972377;775.6044417347193;800.9830699044297;5830.096335068071;5837.211976383134;5936.495397891142;6026.306974697878;6032.336442991267;557.328419254797;563.2846285217832;583.4661284924332;592.4164697772691;593.679413116912;3721.292954338667;3796.772193668389;4071.6742660587083;4152.709310348307;4176.498820141603;10759.731006724895;13282.136661117189;13870.722585548232;13905.721885562232;13908;13741.725165496635;13769.793453098211;13833.930830345784;13838.515651952133;13838.584842454742;8937.955310223408;10021.448820314807;10167.949160254298;10233.334833235816;10267.35722284431;5287.418383977836;6498.285188629342;6501.869962600627;6663.466922646274;6696.129503164558;9943.651972180938;12031.578894738643;12111.636650900487;12155.939220303842;12350.567730129402;11623.535058597561;12282.631521054383;12409.317487538272;12637.494500219887;12697.87302127004;9020.819583608421;9031.864522032307;9032.503212323385;9032.90967090347;9054.818903622023;9242.306826987868;9578.329516933747;10261.179105671677;10320.587176512856;10345.896541034494;8362.24739773399;8547.700830470903;8579.828403431771;8601.311895048257;8696.999845017905;8280;8307.604830142107;8310;8413.242808147295;8513.40406171562;7605;7685.577293248925;7766.611669416619;7776.300132987832;7822.13956464777;5792.855178620568;5815.447532484407;5831.329397119385;5990.251218597676;6028.306744724413;5834;5877.294724633071;6031.276246850406;6048.12302216185;6209.727005963677 14:53:18 INFO - PID 1635 | |4;prototype.html;385.44111038993447;403.6236433760876;405.20781871441335;406.01136233271967;413.4108894824874;4938.6542941993985;5106.948930510694;6434.678266086695;6440.484761219103;6445.709943052562;5998.700064996744;6155.815325540229;6253.59351642143;6288.622682639045;6289.559730818844;4017.9799101004455;4410.911781764363;4593.072922651135;4895.16782147035;4940.752962351886;4254.510731265897;4819.951800482003;4892.828750993716;4928.97535512322;4935.950640493603;4214.051838336381;4704.647151463654;4869.220924652056;4880.536349046835;4882.755862206894;4257.872263832074;4459.687821852472;4662.440507139148;4892.678976676313;4905.2642103684375;3902.8633997810225;3983.203359328117;4115.794210289474;4314.288142456491;4357.586029327209;5572.693501857396;5886;5997.310309314441;6132.448079672851;6198.946179149555;5024.949750502485;5471.206675032138;5683.5169010634145;5696.6866822324755;5727.914081288784;5402.729863506809;5725.196563082615;6701.497387695942;6739.460843132538;6749.223839258499;4591.885202869921;4829.203181475087;4838.177509823338;4848.7090774553635;4852.951470485285;5803.332616749086;6341;6829.829254268633;6950.826229344257;7041.507094503387;3988.880333589939;4814.0503096246075;4906.803727850917;4922.876928076755;4926.236433352836;4179.937300940488;4189.036521600049;4439.445069366329;5056.747162641853;5071.721055341992;5423.322084739408;5623.522000629992;5677.659340439587;5686.943130568724;5694.288213973254;6012.428819262163;6016.639001659914;6385.425311721874;6392.680365981682;6831.28271531482;1707.2488105233658;1733.0901276829766;1736.40094167512;1739.9739003914951;1742.3553285284527;1134.4554613785265;1179.3867189061812;1182.4442512019336;1188.2751521571834;1189.7204157023266;5693.918155550432;6173.012318029094;6252.249730032334;6288.371162883766;6350.793349263625;5983.910241346383;6200.472959798378;6854.127418008809;6867.313268673192;6877.724891004304;5722.399148089474;5783.363829978699;6243.219597550306;6287.434130928238;6305.8108256752375;3578.499010138583;3592.419335492375;3615.5661320641693;3616.8914932552066;3626.963730362663;5940.821775346746;6195;6990.370866622028;7090.987184481606;7184.281571842878;6187.319394866561;6665.433438157714;6667.633280169637;6786.049953006583;7054;5318.361796584435;7113.466490013269;7113.893291600733;7170.569765813963;7175.103112110986;5972.850678733066;6616.569922954993;6729.259781423942;6936.20233673134;6942.548734332252;6748.223954245128;6775.695093720696;6783.286685325909;6799.954027353717;6804.4896632753735;5929.466348028698;6392.840178995562;6548.345165483413;6761.03929860335;7059.329363710439;3889.533256009297;4026.557078721397;4841.68529045625;4869.537393947711;4889.731064791328;6026.909596356146;6348.825467288566;6526.44525215343;6827.93172068273;6928.411085057625;6329.8101056966525;6573.882439985118;6585.617020425764;6640.435562977201;6710;6403.263624683034;6491;6697.933020669731;6712;6741.865162696817;6226.501879849692;6348.7144627932985;6614.735410583523;6629.867402652017;6644.269130395749;5542.944570554243;5785.27684039495;6661.600303981777;6684.565503242274;6757.493188010821;5417;6303.653299068412;6563.967180164069;6566.997065894948;6578.671066446755 14:53:18 INFO - PID 1635 | |5;yui.html;657.4904449051984;716.9928300716991;735.6873328835244;741.1217707017186;758.2303961479099;13369.866301336984;15451;15509.612259693513;15699.90100692952;15873.920630396846;12040.397980100981;15240.618984525392;15245.466408675671;15380.230988450561;15435.382584696627;930.585889279271;950.6815216902346;950.7908260182749;952.9761755956088;958.4919992404037;924.4499522783933;953.0517135450202;954.5799848066863;954.6754103604773;961.1445813226235;1004.2367800471658;1019.1031891935124;1025.574386629548;1025.902539258773;1026.9127124194451;876.4215617692292;1017.730301470111;1018.8522664213722;1021.2238698589089;1025.5128813813453;746.9215732348134;749.7263498822945;761.0600907878766;762.5500954436909;762.9237076292382;13013.609591712264;16268;16678.58303542421;16754.916225418918;16838.494845154666;14638.41446342145;14778.778318325234;15041.172735499544;15061.698766024618;15599.376024958989;11233.831492527619;11342.638486855127;11638.76722465546;11775.293482390998;11871.10966677494;3025.2134445044226;3031.742301904341;3051.0131798182138;3052.1759125036115;3088.8918887838818;102862;109516.4524177382;112485.43757281243;113609.86390136158;114272.42863785628;9913.65302214419;11526.366049867338;11545.98533606116;11565.016973557342;11651.825222621668;24228.273151805475;24524.877375613363;24806.627900581505;24810.50378992446;24829.5034099317;7873.724419645285;7886.408519360956;7968.442209045427;8087.676492940334;8098.838023239502;4070.3690927906223;4157.688173387008;4174.123434078878;4191.97904010478;4193.559676234013;3633.4731463937583;4093.5906409359422;4115.238680844053;4151.356539736345;4151.377293406013;682.931706829323;760.5702777930452;777.40916903154;795.3239746215786;810.266708628692;558.2212813125667;563.312758434709;565.7425871228581;580.1587697838152;593.3147214966721;9995.250356223312;10000.699979000641;10078.445685487368;10146.188304935587;10262.692119236435;553.6733327336891;585.8857522783017;589.590234786819;604.6704546022428;606.4814583531106;4566.834090648399;4677.7193368398;4688.882777930511;4728.905421891543;4780.928286075712;97622.51188744011;101602.98397016083;101619.49190254002;102824.48587757013;104554.47722761337;78554.6072269635;84383;88219.55890220508;89326;90730.54634726916;59189.704051479464;71983;73798.35814776696;77228.22771772323;78601.42797144025;10904.400257985886;10924.399158046384;10932.453377331261;10963.232573719763;11184.440777961232;31382.686173137976;34618.82690586531;35243.82378088093;36225.637743622225;38325.4251186238;46523.76738116288;46950;47008.764956175;47203;47345;13177.868221317663;13278.468861245441;13398.397072131973;13449.529266475627;13677.24775137377;14133.505327313906;14239.73091529709;14357.425702971765;14456;14672.169187546522;13570.253636050113;13607.863921361055;13611.387487563281;13670.52153174674;13807.654808629864;11735.823962640396;13345;13490.527831526242;13491.123076999964;14096.929515352358;11264.87351264867;12745.17156384832;12745.362731863184;13037.804432933324;13725.725485490433;9673.969535841183;9879.555420006262;9902.554385052546;10004.549795259376;10352.689419317432;9836;10252.948735256276;10746.301490403099;11121.777564448828;11232.382218978035 14:53:18 INFO - PID 1635 | __end_tp_report 14:53:18 INFO - PID 1635 | __start_cc_report 14:53:18 INFO - PID 1635 | _x_x_mozilla_cycle_collect,438 14:53:18 INFO - PID 1635 | __end_cc_report 14:53:18 INFO - PID 1635 | __startTimestamp1490737998735__endTimestamp 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | ------- Summary: start ------- 14:53:18 INFO - PID 1635 | Number of tests: 6 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6081.54 Median:6308.40 stddev:775.31 (12.3%) stddev-sans-first:736.53 14:53:18 INFO - PID 1635 | Values: 2904.0 2962.2 3520.3 3553.9 3578.7 6119.7 6172.4 6175.3 6237.1 6239.2 5118.0 6137.9 6167.8 6185.3 6194.8 4571.2 4723.4 5596.9 5647.8 5720.7 4574.4 5601.4 5645.9 5716.7 5746.5 5618.0 5673.2 5683.7 5699.1 5762.9 4614.4 5452.9 5530.9 5564.3 5597.4 5562.4 5572.6 5575.2 5619.9 5648.4 5013.3 5946.5 6268.7 6269.3 6291.2 6367.1 6425.8 6564.5 6619.4 6644.0 6761.7 6771.8 6777.3 6782.8 6794.2 5460.6 6244.7 6293.5 6303.4 6309.2 6645.8 6702.3 6837.3 6861.4 6927.3 6061.5 6308.4 6451.2 6532.5 6558.0 5511.8 6520.4 6530.6 6547.0 6558.2 5116.6 6547.6 6561.5 6636.5 6637.6 6547.4 6633.6 6674.7 6689.8 6692.2 5065.0 5268.7 5292.8 5300.5 5303.2 4636.4 4688.5 4719.2 4773.8 4809.3 6296.2 6319.5 6337.6 6345.7 6346.5 6739.5 6772.5 6804.1 6817.0 6832.6 6146.7 6260.0 6277.9 6291.8 6331.8 5916.5 5944.7 5963.9 5975.2 5991.7 6871.0 6943.2 6984.5 6998.9 7008.2 6474.8 6533.2 6690.7 6713.5 6837.0 6859.3 6992.4 6995.6 7004.6 7031.1 6515.3 6611.2 6624.8 6628.6 6643.3 6459.6 6524.8 6590.9 6686.8 6788.8 5460.5 6362.9 6401.8 6469.4 6493.3 5307.8 5374.5 6139.1 6488.8 6490.9 6159.0 6300.2 6347.4 6354.3 6386.3 6217.4 6440.0 6461.3 6475.8 6505.0 5457.9 5647.5 6278.2 6327.9 6338.1 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:53919.57 Median:34185.16 stddev:109442.29 (320.1%) stddev-sans-first:109676.52 14:53:18 INFO - PID 1635 | Values: 759.1 804.8 835.1 1285.6 1303.8 37611.0 38515.8 38610.0 38701.4 38706.4 30820.5 33741.5 33757.6 38953.4 39416.8 7941.8 8318.4 8459.4 8652.2 8711.0 7268.9 8442.7 8600.3 8703.0 8705.8 8625.3 8637.6 8697.6 8709.7 8739.6 8655.2 8689.0 8712.0 8732.3 8774.5 8639.7 8701.9 8720.7 8723.4 8735.1 38659.6 38802.0 38810.0 38840.8 38863.8 2516.4 3005.5 3023.5 3051.5 3059.2 27983.7 27992.6 28038.0 28232.3 28297.7 2805.2 2831.2 2863.8 2865.5 2866.3 347096.0 347198.0 348718.0 351194.0 351227.0 50656.0 50839.0 50913.0 50915.5 50932.7 51547.0 51849.0 52118.0 52187.5 52264.7 14387.9 14654.1 14736.2 14744.5 14788.6 11820.8 11971.6 11983.6 11992.3 11994.8 13381.5 13529.4 13633.7 13682.0 13778.0 5388.5 6029.7 6071.8 6093.3 6117.9 933.9 965.6 981.1 1007.8 1025.9 24371.0 24540.9 24759.9 24778.4 24800.6 1102.4 1113.4 1136.0 1145.4 1151.6 11939.9 12592.5 12637.7 12638.7 12759.3 484561.0 486808.6 488522.0 489376.0 489761.0 51752.7 56275.0 56451.9 56736.7 56784.9 1142.5 1291.1 1499.5 1500.4 1513.9 24055.6 26956.7 28116.3 28488.3 29041.9 38205.0 38329.2 38362.0 38472.2 38476.4 385527.1 385754.1 386737.2 387285.0 387308.1 30626.5 31280.5 31346.8 31353.0 31382.9 40019.8 40495.0 40859.0 40880.8 40989.6 28836.9 28985.9 29586.4 29633.9 29889.9 28880.0 28913.4 29475.1 29649.0 29728.1 33063.2 33512.0 33790.7 34181.3 34189.0 27518.3 29154.9 29534.9 29869.4 29947.0 21066.2 23148.0 23327.8 23406.2 23562.2 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:41711.03 Median:21110.42 stddev:120845.84 (572.4%) stddev-sans-first:121144.99 14:53:18 INFO - PID 1635 | Values: 377.4 430.3 434.5 441.3 457.0 18589.3 20187.6 20927.1 20989.0 21009.5 22259.3 22268.3 23328.9 23379.3 23434.6 6112.8 6341.4 6357.7 6365.0 6367.2 5944.6 6191.1 6240.3 6258.9 6263.7 6050.6 6188.3 6214.3 6232.2 6232.8 6124.3 6195.5 6207.0 6243.1 6247.6 5566.2 5575.1 5602.5 5604.1 5615.3 27079.0 27259.9 27878.9 28208.6 28240.0 1998.6 2004.0 2014.6 2019.6 2054.3 15754.0 16595.1 16719.0 16887.7 16924.7 1695.3 1700.8 1717.0 1719.1 1727.4 139594.3 146430.0 146513.3 147007.3 147216.0 12483.6 12588.9 12611.0 12742.9 12876.8 16325.9 16343.7 16349.8 16367.9 16379.6 8802.8 9003.8 9082.1 9275.4 9276.1 8633.8 8707.7 8729.3 8908.1 8912.6 7870.5 8040.5 8069.4 8094.6 8139.9 2095.9 2187.5 2190.7 2191.3 2387.0 728.8 768.6 870.4 946.9 948.4 15672.8 15963.0 18627.8 19881.5 21159.3 810.4 945.8 946.4 960.7 969.2 10270.4 10780.8 10792.3 10898.2 10925.3 715035.0 716724.0 721516.1 725186.4 728816.0 161355.0 161961.0 162884.0 163947.0 164967.0 1171.3 1179.9 1204.2 1205.3 1246.8 20469.8 20818.8 21022.7 21040.7 21061.6 63999.0 64044.0 64412.0 64988.0 65872.7 88187.0 90387.5 90795.0 92647.0 92891.0 12538.7 12540.3 12667.9 12832.9 12847.6 13725.0 13753.4 14044.4 14164.2 14181.9 9039.6 9647.9 11782.8 11843.3 11844.8 9119.0 9811.8 10727.5 10914.0 11092.3 10358.5 11683.8 11995.9 12062.7 12164.3 12060.6 12506.9 12690.6 12806.6 12909.9 11661.8 11826.8 11860.6 11881.6 12009.8 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:6621.82 Median:3759.03 stddev:4067.32 (108.2%) stddev-sans-first:4058.78 14:53:18 INFO - PID 1635 | Values: 1260.6 1344.2 1384.0 1406.3 1420.6 9466.7 10329.7 10684.9 10712.7 10959.4 9207.1 9293.8 10492.1 10593.2 10766.9 2206.0 2329.9 2379.5 2477.9 2481.1 2329.3 2407.2 2407.4 2424.7 2452.8 2392.1 2455.7 2463.1 2465.5 2471.1 2051.3 2364.8 2426.0 2438.9 2446.8 2367.5 2422.4 2433.9 2439.8 2464.9 10655.3 10655.5 10717.3 10732.8 10907.0 6177.6 6402.4 6413.1 6421.9 6425.4 3082.0 3235.9 3882.0 3885.1 4000.0 1092.4 1108.0 1123.5 1133.6 1168.9 13314.0 13727.2 14834.8 15040.8 15094.5 5264.7 5383.3 5399.5 5418.3 5443.0 11213.9 11221.3 11289.5 11348.5 11392.3 6093.4 6212.3 6254.7 6260.8 6269.9 5810.2 5885.7 5954.9 5956.9 6000.0 4117.7 4301.0 4338.4 4389.4 4394.1 1095.4 1100.0 1101.3 1114.6 1117.2 741.9 754.7 771.1 775.6 801.0 5830.1 5837.2 5936.5 6026.3 6032.3 557.3 563.3 583.5 592.4 593.7 3721.3 3796.8 4071.7 4152.7 4176.5 10759.7 13282.1 13870.7 13905.7 13908.0 13741.7 13769.8 13833.9 13838.5 13838.6 8938.0 10021.4 10167.9 10233.3 10267.4 5287.4 6498.3 6501.9 6663.5 6696.1 9943.7 12031.6 12111.6 12155.9 12350.6 11623.5 12282.6 12409.3 12637.5 12697.9 9020.8 9031.9 9032.5 9032.9 9054.8 9242.3 9578.3 10261.2 10320.6 10345.9 8362.2 8547.7 8579.8 8601.3 8697.0 8280.0 8307.6 8310.0 8413.2 8513.4 7605.0 7685.6 7766.6 7776.3 7822.1 5792.9 5815.4 5831.3 5990.3 6028.3 5834.0 5877.3 6031.3 6048.1 6209.7 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5397.92 Median:5956.84 stddev:1612.67 (27.1%) stddev-sans-first:1572.69 14:53:18 INFO - PID 1635 | Values: 385.4 403.6 405.2 406.0 413.4 4938.7 5106.9 6434.7 6440.5 6445.7 5998.7 6155.8 6253.6 6288.6 6289.6 4018.0 4410.9 4593.1 4895.2 4940.8 4254.5 4820.0 4892.8 4929.0 4936.0 4214.1 4704.6 4869.2 4880.5 4882.8 4257.9 4459.7 4662.4 4892.7 4905.3 3902.9 3983.2 4115.8 4314.3 4357.6 5572.7 5886.0 5997.3 6132.4 6198.9 5024.9 5471.2 5683.5 5696.7 5727.9 5402.7 5725.2 6701.5 6739.5 6749.2 4591.9 4829.2 4838.2 4848.7 4853.0 5803.3 6341.0 6829.8 6950.8 7041.5 3988.9 4814.1 4906.8 4922.9 4926.2 4179.9 4189.0 4439.4 5056.7 5071.7 5423.3 5623.5 5677.7 5686.9 5694.3 6012.4 6016.6 6385.4 6392.7 6831.3 1707.2 1733.1 1736.4 1740.0 1742.4 1134.5 1179.4 1182.4 1188.3 1189.7 5693.9 6173.0 6252.2 6288.4 6350.8 5983.9 6200.5 6854.1 6867.3 6877.7 5722.4 5783.4 6243.2 6287.4 6305.8 3578.5 3592.4 3615.6 3616.9 3627.0 5940.8 6195.0 6990.4 7091.0 7184.3 6187.3 6665.4 6667.6 6786.0 7054.0 5318.4 7113.5 7113.9 7170.6 7175.1 5972.9 6616.6 6729.3 6936.2 6942.5 6748.2 6775.7 6783.3 6800.0 6804.5 5929.5 6392.8 6548.3 6761.0 7059.3 3889.5 4026.6 4841.7 4869.5 4889.7 6026.9 6348.8 6526.4 6827.9 6928.4 6329.8 6573.9 6585.6 6640.4 6710.0 6403.3 6491.0 6697.9 6712.0 6741.9 6226.5 6348.7 6614.7 6629.9 6644.3 5542.9 5785.3 6661.6 6684.6 6757.5 5417.0 6303.7 6564.0 6567.0 6578.7 14:53:18 INFO - PID 1635 | 14:53:18 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:19479.39 Median:24820.00 stddev:28143.52 (113.4%) stddev-sans-first:28186.98 14:53:18 INFO - PID 1635 | Values: 657.5 717.0 735.7 741.1 758.2 13369.9 15451.0 15509.6 15699.9 15873.9 12040.4 15240.6 15245.5 15380.2 15435.4 930.6 950.7 950.8 953.0 958.5 924.4 953.1 954.6 954.7 961.1 1004.2 1019.1 1025.6 1025.9 1026.9 876.4 1017.7 1018.9 1021.2 1025.5 746.9 749.7 761.1 762.6 762.9 13013.6 16268.0 16678.6 16754.9 16838.5 14638.4 14778.8 15041.2 15061.7 15599.4 11233.8 11342.6 11638.8 11775.3 11871.1 3025.2 3031.7 3051.0 3052.2 3088.9 102862.0 109516.5 112485.4 113609.9 114272.4 9913.7 11526.4 11546.0 11565.0 11651.8 24228.3 24524.9 24806.6 24810.5 24829.5 7873.7 7886.4 7968.4 8087.7 8098.8 4070.4 4157.7 4174.1 4192.0 4193.6 3633.5 4093.6 4115.2 4151.4 4151.4 682.9 760.6 777.4 795.3 810.3 558.2 563.3 565.7 580.2 593.3 9995.3 10000.7 10078.4 10146.2 10262.7 553.7 585.9 589.6 604.7 606.5 4566.8 4677.7 4688.9 4728.9 4780.9 97622.5 101603.0 101619.5 102824.5 104554.5 78554.6 84383.0 88219.6 89326.0 90730.5 59189.7 71983.0 73798.4 77228.2 78601.4 10904.4 10924.4 10932.5 10963.2 11184.4 31382.7 34618.8 35243.8 36225.6 38325.4 46523.8 46950.0 47008.8 47203.0 47345.0 13177.9 13278.5 13398.4 13449.5 13677.2 14133.5 14239.7 14357.4 14456.0 14672.2 13570.3 13607.9 13611.4 13670.5 13807.7 11735.8 13345.0 13490.5 13491.1 14096.9 11264.9 12745.2 12745.4 13037.8 13725.7 9674.0 9879.6 9902.6 10004.5 10352.7 9836.0 10252.9 10746.3 11121.8 11232.4 14:53:18 INFO - PID 1635 | -------- Summary: end -------- 14:53:18 INFO - PID 1635 | 14:53:19 INFO - TEST-INFO | 1635: exit 0 14:53:19 INFO - TEST-OK | dromaeo_css | took 1117729ms 14:53:19 INFO - TEST-START | kraken 14:53:19 INFO - operating with platform_type : mac_ 14:53:19 INFO - Initialising browser for kraken test... 14:53:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile http://localhost:49236/getInfo.html) 14:53:22 INFO - PID 1648 | 2017-03-28 14:53:22.294 plugin-container[1649:10064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 14:53:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:22 INFO - PID 1648 | 2017-03-28 14:53:22.297 plugin-container[1649:10064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.CFPasteboardClient' 14:53:22 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:22 INFO - PID 1648 | 2017-03-28 14:53:22.297 plugin-container[1649:10064] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:53:22 INFO - PID 1648 | __metrics Screen width/height:1600/1200 14:53:22 INFO - PID 1648 | colorDepth:24 14:53:22 INFO - PID 1648 | Browser inner width/height: 1024/689 14:53:22 INFO - PID 1648 | __metrics 14:53:22 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 14:53:22 ERROR - PID 1648 | 1490738002480 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:53:23 INFO - TEST-INFO | 1648: exit 0 14:53:23 INFO - Browser initialized. 14:53:23 INFO - Running cycle 1/1 for kraken test... 14:53:23 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpQmmJYy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:25 INFO - PID 1650 | 2017-03-28 14:53:25.678 plugin-container[1651:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 14:53:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:25 INFO - PID 1650 | 2017-03-28 14:53:25.678 plugin-container[1651:10191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 14:53:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:25 INFO - PID 1650 | 2017-03-28 14:53:25.678 plugin-container[1651:10191] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:53:35 INFO - PID 1650 | RSS: Main: 228163584 14:53:35 INFO - PID 1650 | 14:53:38 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:39 INFO - PID 1650 | RSS: Main: 243679232 14:53:39 INFO - PID 1650 | 14:53:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 14:53:42 INFO - PID 1650 | RSS: Main: 252116992 14:53:42 INFO - PID 1650 | 14:53:46 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 14:53:46 INFO - PID 1650 | RSS: Main: 254353408 14:53:46 INFO - PID 1650 | 14:53:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 14:53:49 INFO - PID 1650 | RSS: Main: 255414272 14:53:49 INFO - PID 1650 | 14:53:51 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:53:52 INFO - PID 1650 | RSS: Main: 257150976 14:53:52 INFO - PID 1650 | 14:53:54 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 14:53:55 INFO - PID 1650 | RSS: Main: 263008256 14:53:55 INFO - PID 1650 | 14:53:59 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 14:53:59 INFO - PID 1650 | RSS: Main: 260554752 14:53:59 INFO - PID 1650 | 14:54:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 14:54:03 INFO - PID 1650 | RSS: Main: 261824512 14:54:03 INFO - PID 1650 | 14:54:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:54:06 INFO - PID 1650 | RSS: Main: 261775360 14:54:06 INFO - PID 1650 | 14:54:07 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:54:08 INFO - PID 1650 | RSS: Main: 261779456 14:54:08 INFO - PID 1650 | 14:54:10 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:54:11 INFO - PID 1650 | RSS: Main: 261988352 14:54:11 INFO - PID 1650 | 14:54:14 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:15 INFO - PID 1650 | RSS: Main: 262586368 14:54:15 INFO - PID 1650 | 14:54:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:19 INFO - PID 1650 | RSS: Main: 262533120 14:54:19 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 14:54:21 INFO - PID 1650 | RSS: Main: 265814016 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | __start_tp_report 14:54:21 INFO - PID 1650 | _x_x_mozilla_page_load 14:54:21 INFO - PID 1650 | _x_x_mozilla_page_load_details 14:54:21 INFO - PID 1650 | |i|pagename|runs| 14:54:21 INFO - PID 1650 | |0;ai-astar;144;191;124;123;122;121;122;123;120;122 14:54:21 INFO - PID 1650 | |1;audio-beat-detection;152;119;124;200;126;125;146;119;141;118 14:54:21 INFO - PID 1650 | |2;audio-dft;213;222;228;220;218;222;214;206;210;214 14:54:21 INFO - PID 1650 | |3;audio-fft;80;58;58;87;83;92;86;55;62;62 14:54:21 INFO - PID 1650 | |4;audio-oscillator;89;75;86;76;73;92;85;71;72;86 14:54:21 INFO - PID 1650 | |5;imaging-gaussian-blur;91;92;91;91;108;90;92;90;92;91 14:54:21 INFO - PID 1650 | |6;imaging-darkroom;144;100;98;115;160;162;162;100;104;105 14:54:21 INFO - PID 1650 | |7;imaging-desaturate;100;99;163;149;103;104;141;163;102;102 14:54:21 INFO - PID 1650 | |8;json-parse-financial;69;67;68;68;68;68;68;67;68;67 14:54:21 INFO - PID 1650 | |9;json-stringify-tinderbox;60;66;61;70;71;65;61;62;63;63 14:54:21 INFO - PID 1650 | |10;stanford-crypto-aes;86;86;90;86;90;88;117;115;114;89 14:54:21 INFO - PID 1650 | |11;stanford-crypto-ccm;157;150;140;184;152;146;166;177;230;175 14:54:21 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;199;200;195;198;223;213;195;194;196;236 14:54:21 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;70;70;77;62;92;83;70;97;106;100 14:54:21 INFO - PID 1650 | __end_tp_report 14:54:21 INFO - PID 1650 | __start_cc_report 14:54:21 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1012 14:54:21 INFO - PID 1650 | __end_cc_report 14:54:21 INFO - PID 1650 | __startTimestamp1490738061657__endTimestamp 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | ------- Summary: start ------- 14:54:21 INFO - PID 1650 | Number of tests: 14 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:131.20 Median:123.00 stddev:22.13 (18.0%) stddev-sans-first:22.99 14:54:21 INFO - PID 1650 | Values: 144.0 191.0 124.0 123.0 122.0 121.0 122.0 123.0 120.0 122.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:133.50 stddev:25.24 (18.9%) stddev-sans-first:26.18 14:54:21 INFO - PID 1650 | Values: 152.0 119.0 124.0 200.0 126.0 125.0 146.0 119.0 141.0 118.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:216.70 Median:219.00 stddev:6.53 (3.0%) stddev-sans-first:6.79 14:54:21 INFO - PID 1650 | Values: 213.0 222.0 228.0 220.0 218.0 222.0 214.0 206.0 210.0 214.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:72.30 Median:81.50 stddev:14.48 (17.8%) stddev-sans-first:15.08 14:54:21 INFO - PID 1650 | Values: 80.0 58.0 58.0 87.0 83.0 92.0 86.0 55.0 62.0 62.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:85.50 stddev:7.85 (9.2%) stddev-sans-first:7.70 14:54:21 INFO - PID 1650 | Values: 89.0 75.0 86.0 76.0 73.0 92.0 85.0 71.0 72.0 86.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:91.00 stddev:5.39 (5.9%) stddev-sans-first:5.68 14:54:21 INFO - PID 1650 | Values: 91.0 92.0 91.0 91.0 108.0 90.0 92.0 90.0 92.0 91.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:125.00 Median:152.00 stddev:28.37 (18.7%) stddev-sans-first:29.25 14:54:21 INFO - PID 1650 | Values: 144.0 100.0 98.0 115.0 160.0 162.0 162.0 100.0 104.0 105.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:122.60 Median:145.00 stddev:27.78 (19.2%) stddev-sans-first:28.24 14:54:21 INFO - PID 1650 | Values: 100.0 99.0 163.0 149.0 103.0 104.0 141.0 163.0 102.0 102.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.50 14:54:21 INFO - PID 1650 | Values: 69.0 67.0 68.0 68.0 68.0 68.0 68.0 67.0 68.0 67.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:3.79 (5.9%) stddev-sans-first:3.71 14:54:21 INFO - PID 1650 | Values: 60.0 66.0 61.0 70.0 71.0 65.0 61.0 62.0 63.0 63.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:87.00 stddev:13.38 (15.4%) stddev-sans-first:13.68 14:54:21 INFO - PID 1650 | Values: 86.0 86.0 90.0 86.0 90.0 88.0 117.0 115.0 114.0 89.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:167.70 Median:170.50 stddev:26.25 (15.4%) stddev-sans-first:27.56 14:54:21 INFO - PID 1650 | Values: 157.0 150.0 140.0 184.0 152.0 146.0 166.0 177.0 230.0 175.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.90 Median:199.50 stddev:14.38 (7.2%) stddev-sans-first:15.09 14:54:21 INFO - PID 1650 | Values: 199.0 200.0 195.0 198.0 223.0 213.0 195.0 194.0 196.0 236.0 14:54:21 INFO - PID 1650 | 14:54:21 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.70 Median:73.50 stddev:15.20 (20.7%) stddev-sans-first:15.41 14:54:21 INFO - PID 1650 | Values: 70.0 70.0 77.0 62.0 92.0 83.0 70.0 97.0 106.0 100.0 14:54:21 INFO - PID 1650 | -------- Summary: end -------- 14:54:21 INFO - PID 1650 | 14:54:22 INFO - TEST-INFO | 1650: exit 0 14:54:22 INFO - TEST-OK | kraken | took 63039ms 14:54:22 INFO - SUITE-END | took 1180s 14:54:22 INFO - Completed test suite (00:19:41) 14:54:22 INFO - javascript benchmark 14:54:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7705.902157283199, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2904.0, 2962.229820246736, 3520.3135388599235, 3553.893383198504, 3578.731595130366, 6119.694015299239, 6172.413620706031, 6175.289841668209, 6237.126802247689, 6239.188905442292, 5117.974410127954, 6137.928623859632, 6167.828112658592, 6185.319614842364, 6194.752209911598, 4571.1771881061395, 4723.3859598252175, 5596.916046259299, 5647.802326918559, 5720.713964301789, 4574.382458368128, 5601.411851755568, 5645.915311270334, 5716.657000579958, 5746.454086861741, 5618.0449323615085, 5673.177389278573, 5683.687397193153, 5699.145128230757, 5762.855928601776, 4614.3770590970125, 5452.863678408032, 5530.917036244459, 5564.27664403629, 5597.384287728347, 5562.443755624446, 5572.637778544422, 5575.1915972184215, 5619.859503512404, 5648.378678345379, 5013.348264725599, 5946.524278057745, 6268.749250029993, 6269.341719119472, 6291.213598300213, 6367.076773867763, 6425.8393540161405, 6564.5404821662505, 6619.371159739817, 6644.0, 6761.729530818761, 6771.830704232384, 6777.288384719632, 6782.76260330891, 6794.184697836242, 5460.645058071213, 6244.687765611701, 6293.527985401068, 6303.432691057781, 6309.242890853127, 6645.833854153685, 6702.296258892844, 6837.316268373223, 6861.382475577222, 6927.3419025192525, 6061.454469097803, 6308.400701933309, 6451.161349024596, 6532.542722009509, 6558.0, 5511.779528818883, 6520.445762110274, 6530.640814755234, 6546.967265163739, 6558.213014438298, 5116.616253780981, 6547.639879806561, 6561.475081993429, 6636.502262330345, 6637.568558043712, 6547.377999090078, 6633.568818026813, 6674.699638516278, 6689.832754181184, 6692.163479565054, 5065.0, 5268.736563171826, 5292.788288468495, 5300.469953004668, 5303.15149576066, 4636.420447444069, 4688.46082700487, 4719.197736384823, 4773.785179666854, 4809.278608208727, 6296.212973378328, 6319.526035547352, 6337.6197428154455, 6345.682715864188, 6346.460550853137, 6739.460843132636, 6772.492063095287, 6804.08144900442, 6816.965915170492, 6832.624205668736, 6146.692665366803, 6260.029695397129, 6277.8744425111245, 6291.822629572128, 6331.810045698637, 5916.497097746654, 5944.7324870380935, 5963.940360596339, 5975.223220981332, 5991.700414979234, 6870.965645171742, 6943.236244013155, 6984.476164287698, 6998.860022799618, 7008.22909479967, 6474.805755827333, 6533.248676402084, 6690.665466726741, 6713.530052896251, 6837.0, 6859.279775623687, 6992.4406047517095, 6995.580265184105, 7004.614746189009, 7031.1211098612675, 6515.2833188348295, 6611.239707433707, 6624.768133115318, 6628.5691430056895, 6643.302453242533, 6459.61242325462, 6524.771632993013, 6590.90113648286, 6686.7993960181275, 6788.796336109925, 5460.481254280837, 6362.904556431565, 6401.775937842153, 6469.385408386196, 6493.253275873146, 5307.761150748149, 5374.489423504917, 6139.093519790236, 6488.772892948724, 6490.902636460362, 6158.969205153946, 6300.243970723543, 6347.365263473615, 6354.301026886947, 6386.297507274289, 6217.378262173746, 6439.967800160969, 6461.321561236003, 6475.7733479328, 6504.9674751625935, 5457.918131228114, 5647.491725744702, 6278.246610406781, 6327.936720632735, 6338.144350512806], "value": 6029.077490682849, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.1004659478518, 804.8390321935614, 835.0939230934435, 1285.5950375631676, 1303.7588046211445, 37611.0, 38515.80742096293, 38610.0, 38701.41947870777, 38706.4194037089, 30820.537691934638, 33741.4816333265, 33757.64969401221, 38953.41569876454, 39416.80291598538, 7941.841163176733, 8318.41771076025, 8459.4078414511, 8652.178043085929, 8711.0417854036, 7268.927310726878, 8442.746717598482, 8600.311975042014, 8702.956485217597, 8705.825883482326, 8625.266852317529, 8637.611307491177, 8697.608607612641, 8709.695160669346, 8739.563021848913, 8655.17775811297, 8689.0, 8712.0, 8732.257758090569, 8774.473531588124, 8639.65441382344, 8701.912980870173, 8720.651173953034, 8723.38936274461, 8735.126487351277, 38659.613403865886, 38802.0, 38810.0, 38840.80579597112, 38863.805680971695, 2516.3709072731817, 3005.549167624874, 3023.5313526403443, 3051.4659934511374, 3059.189314831571, 27983.720162798316, 27992.580111298346, 28038.0, 28232.294192645142, 28297.71702282992, 2805.1724741201397, 2831.164806382122, 2863.7708983281286, 2865.498537755884, 2866.2977570495154, 347096.0, 347198.0, 348718.0, 351194.0, 351227.0, 50656.0, 50839.0, 50913.0, 50915.490845091816, 50932.72668183325, 51547.0, 51849.0, 52117.95764084697, 52187.47812521826, 52264.73867630638, 14387.920905932097, 14654.120752754869, 14736.189509576861, 14744.493436115887, 14788.63028424298, 11820.822687659693, 11971.64085077449, 11983.64049078529, 11992.340421276738, 11994.76010479803, 13381.46474141043, 13529.391177397041, 13633.70479804417, 13682.0, 13777.966652501102, 5388.461153884658, 6029.668368239789, 6071.817845464643, 6093.329733729286, 6117.877642447215, 933.8972713001565, 965.6089283840056, 981.1365997921926, 1007.8437842134481, 1025.9230557708202, 24371.0, 24540.895659695, 24759.87620061888, 24778.380540486633, 24800.627990579796, 1102.3551222534747, 1113.3876367997568, 1135.9886401135882, 1145.4272863568217, 1151.6084531259412, 11939.88060119405, 12592.496300148076, 12637.747245055047, 12638.684032899251, 12759.298238596966, 484561.0, 486808.56595716794, 488522.0, 489376.0, 489761.0, 51752.74123629358, 56275.0, 56451.87096258134, 56736.716316418155, 56784.864302713715, 1142.4687520303098, 1291.0768800307826, 1499.482678475924, 1500.3848422146868, 1513.8940274180923, 24055.639165412184, 26956.730432696208, 28116.297092572848, 28488.287792805346, 29041.854790726757, 38204.9884762096, 38329.23341533209, 38362.04094897538, 38472.19097612798, 38476.42285365778, 385527.0723646364, 385754.0712296421, 386737.19894201047, 387285.0, 387308.1269187272, 30626.540601891436, 31280.530792037684, 31346.843265783526, 31353.0, 31382.901598443947, 40019.79990100031, 40494.9876253096, 40859.0, 40880.79559602183, 40989.59010409858, 28836.855815720788, 28985.855070724512, 29586.40827183401, 29633.851830740707, 29889.85055074798, 28880.0, 28913.42173156483, 29475.115746527637, 29649.0, 29728.108156755334, 33063.17342066371, 33511.99464016083, 33790.66209337875, 34181.31637367288, 34189.0, 27518.312042199104, 29154.854225728737, 29534.852325738233, 29869.402611948073, 29946.95185668491, 21066.226876229288, 23148.0, 23327.76794836638, 23406.18078367249, 23562.175323863583], "value": 17765.31770038065, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.4451556212368, 430.30505733240824, 434.4525897369314, 441.28732097662265, 457.0060119240651, 18589.349372771954, 20187.59624807505, 20927.058282377293, 20989.0, 21009.474763130893, 22259.332220033386, 22268.331950041484, 23328.883355583243, 23379.298621041355, 23434.64848027277, 6112.816615501542, 6341.429271365576, 6357.745690172387, 6365.045243213532, 6367.235931688204, 5944.554158438134, 6191.0713392990965, 6240.282367527724, 6258.937410625904, 6263.718132684017, 6050.606710563799, 6188.319284878659, 6214.316425193225, 6232.158658581102, 6232.781852635158, 6124.295705993801, 6195.535334849904, 6207.0, 6243.125962365272, 6247.5939063960695, 5566.2207290979295, 5575.135853942646, 5602.523785478239, 5604.103343465067, 5615.298087739033, 27079.0, 27259.90960361583, 27878.860605696842, 28208.602268807175, 28240.011599593916, 1998.570307383905, 2003.9899800500905, 2014.617222727677, 2019.5758890632985, 2054.3323419888447, 15754.0, 16595.087270200132, 16718.99686018843, 16887.66224675512, 16924.66150676992, 1695.2710334556136, 1700.7533907583331, 1717.0, 1719.1146559521856, 1727.3954116059404, 139594.30202849023, 146430.0, 146513.26743366214, 147007.26496367555, 147216.0, 12483.56307529232, 12588.937055314665, 12611.0, 12742.872571274354, 12876.80684789729, 16325.91837040831, 16343.67312653764, 16349.836501635069, 16367.91816040912, 16379.59051023734, 8802.823943521094, 9003.819923601492, 9082.137196966278, 9275.39709918853, 9276.07239276067, 8633.827323453495, 8707.738767836974, 8729.301655867515, 8908.10918908104, 8912.598933048028, 7870.488418252795, 8040.545576507462, 8069.3544516439715, 8094.635741391651, 8139.877901831477, 2095.9371218863457, 2187.475005998581, 2190.7371115465926, 2191.3206905859233, 2386.9880650596638, 728.8287252495659, 768.5772824946257, 870.4081224767219, 946.914777670013, 948.4498990585444, 15672.764908526382, 15963.0, 18627.81372186288, 19881.502962425766, 21159.25942592002, 810.4448452809844, 945.8486642137362, 946.4463288976026, 960.6973803251954, 969.2294625772528, 10270.435126067989, 10780.78438431227, 10792.298500597593, 10898.237123401444, 10925.289856159483, 715035.0, 716724.0, 721516.0635542826, 725186.3740681263, 728816.0, 161355.0, 161961.0, 162884.0, 163947.0, 164967.0, 1171.3264872698232, 1179.941002949849, 1204.2172587817813, 1205.2768338996707, 1246.8067449545335, 20469.79530204679, 20818.791812081687, 21022.68465972981, 21040.68438973386, 21061.578768424853, 63999.04001439889, 64044.0, 64412.0, 64988.0, 65872.67063664651, 88187.0, 90387.54806225927, 90795.0, 92647.0, 92891.0, 12538.686532836751, 12540.31028293416, 12667.927156931111, 12832.935835321137, 12847.550335738204, 13725.0, 13753.418356888686, 14044.368003439666, 14164.150150990974, 14181.929090354482, 9039.593218305323, 9647.855282170633, 11782.764344712887, 11843.348615826213, 11844.822327664919, 9119.042500537347, 9811.803763924825, 10727.463626818784, 10914.0, 11092.279001864854, 10358.5338659762, 11683.824742628698, 11995.880041199825, 12062.698432539257, 12164.330961797186, 12060.638180854588, 12506.937465312616, 12690.555830545887, 12806.61580152597, 12909.870901291242, 11661.825072623747, 11826.763464730486, 11860.644180674593, 11881.643550693492, 12009.81985270239], "value": 10676.789384185891, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.6281147061618, 1344.193483909655, 1383.958481245563, 1406.3179358011362, 1420.616433562938, 9466.668666596663, 10329.741756456084, 10684.946575267133, 10712.73218169546, 10959.397233152173, 9207.07929207079, 9293.814123717522, 10492.10817080549, 10593.152547796177, 10766.892331076688, 2206.0403724379867, 2329.8835058247105, 2379.512199998996, 2477.8884950177194, 2481.1440053181623, 2329.2546385156757, 2407.1935901473025, 2407.38611654028, 2424.733279339271, 2452.7792498675117, 2392.1149174805346, 2455.680761501011, 2463.1379017343966, 2465.5192237513693, 2471.0733474946896, 2051.2820512820545, 2364.775346342095, 2425.9757402426017, 2438.90254381232, 2446.804255659552, 2367.4673198530363, 2422.39440139965, 2433.890474928615, 2439.8048156147465, 2464.9260522184363, 10655.254132210748, 10655.52050157737, 10717.303375280582, 10732.78534429307, 10907.018368346888, 6177.62934223948, 6402.391772781578, 6413.134226879358, 6421.903671444932, 6425.389587989133, 3081.984590077035, 3235.854386552611, 3881.971277611435, 3885.1064255221177, 3999.98000010001, 1092.4046394714899, 1107.9612213572566, 1123.5449642894641, 1133.5805751871778, 1168.9298642081503, 13314.0, 13727.245001524912, 14834.777478337834, 15040.774388384183, 15094.54716358511, 5264.684118952875, 5383.300170977748, 5399.541039011649, 5418.349798024262, 5443.0, 11213.887861121448, 11221.270617409842, 11289.475920750594, 11348.54605815758, 11392.25950313227, 6093.421124993119, 6212.285587157535, 6254.6872656366995, 6260.812175634738, 6269.937300627026, 5810.244668193108, 5885.735141918624, 5954.851128721816, 5956.910646340309, 5999.970000149971, 4117.732347397409, 4301.0, 4338.414314067623, 4389.385486031958, 4394.055278115221, 1095.3756358875364, 1099.9560017599367, 1101.2566517600587, 1114.5931734916696, 1117.234694234452, 741.9109706835106, 754.7433872483272, 771.1132197972377, 775.6044417347193, 800.9830699044297, 5830.096335068071, 5837.211976383134, 5936.495397891142, 6026.306974697878, 6032.336442991267, 557.328419254797, 563.2846285217832, 583.4661284924332, 592.4164697772691, 593.679413116912, 3721.292954338667, 3796.772193668389, 4071.6742660587083, 4152.709310348307, 4176.498820141603, 10759.731006724895, 13282.136661117189, 13870.722585548232, 13905.721885562232, 13908.0, 13741.725165496635, 13769.793453098211, 13833.930830345784, 13838.515651952133, 13838.584842454742, 8937.955310223408, 10021.448820314807, 10167.949160254298, 10233.334833235816, 10267.35722284431, 5287.418383977836, 6498.285188629342, 6501.869962600627, 6663.466922646274, 6696.129503164558, 9943.651972180938, 12031.578894738643, 12111.636650900487, 12155.939220303842, 12350.567730129402, 11623.535058597561, 12282.631521054383, 12409.317487538272, 12637.494500219887, 12697.87302127004, 9020.819583608421, 9031.864522032307, 9032.503212323385, 9032.90967090347, 9054.818903622023, 9242.306826987868, 9578.329516933747, 10261.179105671677, 10320.587176512856, 10345.896541034494, 8362.24739773399, 8547.700830470903, 8579.828403431771, 8601.311895048257, 8696.999845017905, 8280.0, 8307.604830142107, 8310.0, 8413.242808147295, 8513.40406171562, 7605.0, 7685.577293248925, 7766.611669416619, 7776.300132987832, 7822.13956464777, 5792.855178620568, 5815.447532484407, 5831.329397119385, 5990.251218597676, 6028.306744724413, 5834.0, 5877.294724633071, 6031.276246850406, 6048.12302216185, 6209.727005963677], "value": 5002.981085256471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.44111038993447, 403.6236433760876, 405.20781871441335, 406.01136233271967, 413.4108894824874, 4938.6542941993985, 5106.948930510694, 6434.678266086695, 6440.484761219103, 6445.709943052562, 5998.700064996744, 6155.815325540229, 6253.59351642143, 6288.622682639045, 6289.559730818844, 4017.9799101004455, 4410.911781764363, 4593.072922651135, 4895.16782147035, 4940.752962351886, 4254.510731265897, 4819.951800482003, 4892.828750993716, 4928.97535512322, 4935.950640493603, 4214.051838336381, 4704.647151463654, 4869.220924652056, 4880.536349046835, 4882.755862206894, 4257.872263832074, 4459.687821852472, 4662.440507139148, 4892.678976676313, 4905.2642103684375, 3902.8633997810225, 3983.203359328117, 4115.794210289474, 4314.288142456491, 4357.586029327209, 5572.693501857396, 5886.0, 5997.310309314441, 6132.448079672851, 6198.946179149555, 5024.949750502485, 5471.206675032138, 5683.5169010634145, 5696.6866822324755, 5727.914081288784, 5402.729863506809, 5725.196563082615, 6701.497387695942, 6739.460843132538, 6749.223839258499, 4591.885202869921, 4829.203181475087, 4838.177509823338, 4848.7090774553635, 4852.951470485285, 5803.332616749086, 6341.0, 6829.829254268633, 6950.826229344257, 7041.507094503387, 3988.880333589939, 4814.0503096246075, 4906.803727850917, 4922.876928076755, 4926.236433352836, 4179.937300940488, 4189.036521600049, 4439.445069366329, 5056.747162641853, 5071.721055341992, 5423.322084739408, 5623.522000629992, 5677.659340439587, 5686.943130568724, 5694.288213973254, 6012.428819262163, 6016.639001659914, 6385.425311721874, 6392.680365981682, 6831.28271531482, 1707.2488105233658, 1733.0901276829766, 1736.40094167512, 1739.9739003914951, 1742.3553285284527, 1134.4554613785265, 1179.3867189061812, 1182.4442512019336, 1188.2751521571834, 1189.7204157023266, 5693.918155550432, 6173.012318029094, 6252.249730032334, 6288.371162883766, 6350.793349263625, 5983.910241346383, 6200.472959798378, 6854.127418008809, 6867.313268673192, 6877.724891004304, 5722.399148089474, 5783.363829978699, 6243.219597550306, 6287.434130928238, 6305.8108256752375, 3578.499010138583, 3592.419335492375, 3615.5661320641693, 3616.8914932552066, 3626.963730362663, 5940.821775346746, 6195.0, 6990.370866622028, 7090.987184481606, 7184.281571842878, 6187.319394866561, 6665.433438157714, 6667.633280169637, 6786.049953006583, 7054.0, 5318.361796584435, 7113.466490013269, 7113.893291600733, 7170.569765813963, 7175.103112110986, 5972.850678733066, 6616.569922954993, 6729.259781423942, 6936.20233673134, 6942.548734332252, 6748.223954245128, 6775.695093720696, 6783.286685325909, 6799.954027353717, 6804.4896632753735, 5929.466348028698, 6392.840178995562, 6548.345165483413, 6761.03929860335, 7059.329363710439, 3889.533256009297, 4026.557078721397, 4841.68529045625, 4869.537393947711, 4889.731064791328, 6026.909596356146, 6348.825467288566, 6526.44525215343, 6827.93172068273, 6928.411085057625, 6329.8101056966525, 6573.882439985118, 6585.617020425764, 6640.435562977201, 6710.0, 6403.263624683034, 6491.0, 6697.933020669731, 6712.0, 6741.865162696817, 6226.501879849692, 6348.7144627932985, 6614.735410583523, 6629.867402652017, 6644.269130395749, 5542.944570554243, 5785.27684039495, 6661.600303981777, 6684.565503242274, 6757.493188010821, 5417.0, 6303.653299068412, 6563.967180164069, 6566.997065894948, 6578.671066446755], "value": 4910.070832125141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.4904449051984, 716.9928300716991, 735.6873328835244, 741.1217707017186, 758.2303961479099, 13369.866301336984, 15451.0, 15509.612259693513, 15699.90100692952, 15873.920630396846, 12040.397980100981, 15240.618984525392, 15245.466408675671, 15380.230988450561, 15435.382584696627, 930.585889279271, 950.6815216902346, 950.7908260182749, 952.9761755956088, 958.4919992404037, 924.4499522783933, 953.0517135450202, 954.5799848066863, 954.6754103604773, 961.1445813226235, 1004.2367800471658, 1019.1031891935124, 1025.574386629548, 1025.902539258773, 1026.9127124194451, 876.4215617692292, 1017.730301470111, 1018.8522664213722, 1021.2238698589089, 1025.5128813813453, 746.9215732348134, 749.7263498822945, 761.0600907878766, 762.5500954436909, 762.9237076292382, 13013.609591712264, 16268.0, 16678.58303542421, 16754.916225418918, 16838.494845154666, 14638.41446342145, 14778.778318325234, 15041.172735499544, 15061.698766024618, 15599.376024958989, 11233.831492527619, 11342.638486855127, 11638.76722465546, 11775.293482390998, 11871.10966677494, 3025.2134445044226, 3031.742301904341, 3051.0131798182138, 3052.1759125036115, 3088.8918887838818, 102862.0, 109516.4524177382, 112485.43757281243, 113609.86390136158, 114272.42863785628, 9913.65302214419, 11526.366049867338, 11545.98533606116, 11565.016973557342, 11651.825222621668, 24228.273151805475, 24524.877375613363, 24806.627900581505, 24810.50378992446, 24829.5034099317, 7873.724419645285, 7886.408519360956, 7968.442209045427, 8087.676492940334, 8098.838023239502, 4070.3690927906223, 4157.688173387008, 4174.123434078878, 4191.97904010478, 4193.559676234013, 3633.4731463937583, 4093.5906409359422, 4115.238680844053, 4151.356539736345, 4151.377293406013, 682.931706829323, 760.5702777930452, 777.40916903154, 795.3239746215786, 810.266708628692, 558.2212813125667, 563.312758434709, 565.7425871228581, 580.1587697838152, 593.3147214966721, 9995.250356223312, 10000.699979000641, 10078.445685487368, 10146.188304935587, 10262.692119236435, 553.6733327336891, 585.8857522783017, 589.590234786819, 604.6704546022428, 606.4814583531106, 4566.834090648399, 4677.7193368398, 4688.882777930511, 4728.905421891543, 4780.928286075712, 97622.51188744011, 101602.98397016083, 101619.49190254002, 102824.48587757013, 104554.47722761337, 78554.6072269635, 84383.0, 88219.55890220508, 89326.0, 90730.54634726916, 59189.704051479464, 71983.0, 73798.35814776696, 77228.22771772323, 78601.42797144025, 10904.400257985886, 10924.399158046384, 10932.453377331261, 10963.232573719763, 11184.440777961232, 31382.686173137976, 34618.82690586531, 35243.82378088093, 36225.637743622225, 38325.4251186238, 46523.76738116288, 46950.0, 47008.764956175, 47203.0, 47345.0, 13177.868221317663, 13278.468861245441, 13398.397072131973, 13449.529266475627, 13677.24775137377, 14133.505327313906, 14239.73091529709, 14357.425702971765, 14456.0, 14672.169187546522, 13570.253636050113, 13607.863921361055, 13611.387487563281, 13670.52153174674, 13807.654808629864, 11735.823962640396, 13345.0, 13490.527831526242, 13491.123076999964, 14096.929515352358, 11264.87351264867, 12745.17156384832, 12745.362731863184, 13037.804432933324, 13725.725485490433, 9673.969535841183, 9879.555420006262, 9902.554385052546, 10004.549795259376, 10352.689419317432, 9836.0, 10252.948735256276, 10746.301490403099, 11121.777564448828, 11232.382218978035], "value": 7452.934083193524, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1661.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 191.0, 124.0, 123.0, 122.0, 121.0, 122.0, 123.0, 120.0, 122.0], "value": 131.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 119.0, 124.0, 200.0, 126.0, 125.0, 146.0, 119.0, 141.0, 118.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 222.0, 228.0, 220.0, 218.0, 222.0, 214.0, 206.0, 210.0, 214.0], "value": 216.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 87.0, 83.0, 92.0, 86.0, 55.0, 62.0, 62.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 75.0, 86.0, 76.0, 73.0, 92.0, 85.0, 71.0, 72.0, 86.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 91.0, 108.0, 90.0, 92.0, 90.0, 92.0, 91.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 100.0, 98.0, 115.0, 160.0, 162.0, 162.0, 100.0, 104.0, 105.0], "value": 125.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 99.0, 163.0, 149.0, 103.0, 104.0, 141.0, 163.0, 102.0, 102.0], "value": 122.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 66.0, 61.0, 70.0, 71.0, 65.0, 61.0, 62.0, 63.0, 63.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 90.0, 86.0, 90.0, 88.0, 117.0, 115.0, 114.0, 89.0], "value": 96.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 150.0, 140.0, 184.0, 152.0, 146.0, 166.0, 177.0, 230.0, 175.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 195.0, 198.0, 223.0, 213.0, 195.0, 194.0, 196.0, 236.0], "value": 204.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 77.0, 62.0, 92.0, 83.0, 70.0, 97.0, 106.0, 100.0], "value": 82.7, "unit": "score"}]}]} 14:54:22 INFO - Return code: 0 14:54:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:54:22 ERROR - # TBPL SUCCESS # 14:54:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:22 INFO - 2017-03-28 14:54:22.954 screenresolution[1655:10587] starting screenresolution argv=screenresolution get 14:54:22 INFO - 2017-03-28 14:54:22.956 screenresolution[1655:10587] Display 0: 1600x1200x32@60 14:54:22 INFO - 2017-03-28 14:54:22.963 screenresolution[1656:10590] starting screenresolution argv=screenresolution list 14:54:22 INFO - Available Modes on Display 0 14:54:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:23 INFO - Intel Iris: 14:54:23 INFO - Chipset Model: Intel Iris 14:54:23 INFO - Type: GPU 14:54:23 INFO - Bus: Built-In 14:54:23 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:23 INFO - Vendor: Intel (0x8086) 14:54:23 INFO - Device ID: 0x0a2e 14:54:23 INFO - Revision ID: 0x0009 14:54:23 INFO - Displays: 14:54:23 INFO - Display: 14:54:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:23 INFO - Display Serial Number: 1600x1200 60 14:54:23 INFO - Main Display: Yes 14:54:23 INFO - Mirror: Off 14:54:23 INFO - Online: Yes 14:54:23 INFO - Rotation: Supported 14:54:23 INFO - Return code: 0 14:54:23 INFO - Running post-action listener: _resource_record_post_action 14:54:23 INFO - [mozharness: 2017-03-28 21:54:23.198488Z] Finished run-tests step (success) 14:54:23 INFO - Running post-run listener: _resource_record_post_run 14:54:23 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39111168; Write bytes: 516746240; Read time: 250; Write time: 1607 14:54:23 INFO - TinderboxPrint: CPU usage
54.1% 14:54:23 INFO - TinderboxPrint: I/O read bytes / time
39,111,168 / 250 14:54:23 INFO - TinderboxPrint: I/O write bytes / time
516,746,240 / 1,607 14:54:23 INFO - TinderboxPrint: CPU idle
2,205.7 (45.9%) 14:54:23 INFO - TinderboxPrint: CPU system
963.8 (20.0%) 14:54:23 INFO - TinderboxPrint: CPU user
1,640.2 (34.1%) 14:54:23 INFO - TinderboxPrint: Swap in / out
250,916,864 / 0 14:54:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207047168; Write bytes: 204101120; Read time: 15686; Write time: 426 14:54:23 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 37116416; Write bytes: 301803008; Read time: 230; Write time: 1150 14:54:23 INFO - Running post-run listener: _upload_blobber_files 14:54:23 INFO - Blob upload gear active. 14:54:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:23 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 14:54:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:54:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:54:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:25 INFO - (blobuploader) - INFO - Done attempting. 14:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:54:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:26 INFO - (blobuploader) - INFO - Done attempting. 14:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:54:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:27 INFO - (blobuploader) - INFO - Done attempting. 14:54:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:54:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:29 INFO - (blobuploader) - INFO - Done attempting. 14:54:29 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:29 INFO - Return code: 0 14:54:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bbebacfddb7e9795d2b04aeff44b33525d0917a4a4bb42a92b30df838388ab01fba5fa8bf55adab004b0a51f966879dc15a3443736251edc9e49c2de37d6820", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86e73cc58e296716230ee3c50671cfaa165ed9a99266358c6cbd3fd9175637bcbfe9f097d4d052560fa7b0daaeb8a351e8f91f5d591b6d6736dd2bc6ec2389df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20803149617024abda13e0b307c1b16ae7389950677823fea45b8eceaadcfaf1800c361e899b386da8aec3661ee8932622ff53156d86d4e119020bcb3645b61d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4ef3025762925dbdbf0690b3af8aa5e36bdbb931e609891453130fdbd77afdbf83dfa4e212457732b05bb26c9187a48940b37cc123e6ea82fa64451c0f7e99"} 14:54:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:29 INFO - Contents: 14:54:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bbebacfddb7e9795d2b04aeff44b33525d0917a4a4bb42a92b30df838388ab01fba5fa8bf55adab004b0a51f966879dc15a3443736251edc9e49c2de37d6820", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86e73cc58e296716230ee3c50671cfaa165ed9a99266358c6cbd3fd9175637bcbfe9f097d4d052560fa7b0daaeb8a351e8f91f5d591b6d6736dd2bc6ec2389df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20803149617024abda13e0b307c1b16ae7389950677823fea45b8eceaadcfaf1800c361e899b386da8aec3661ee8932622ff53156d86d4e119020bcb3645b61d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4ef3025762925dbdbf0690b3af8aa5e36bdbb931e609891453130fdbd77afdbf83dfa4e212457732b05bb26c9187a48940b37cc123e6ea82fa64451c0f7e99"} 14:54:29 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:29 INFO - Copying logs to upload dir... 14:54:29 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.084358 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-28 14:54:29.652203) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:29.656053) ========= 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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/Firefox/sha512/8bbebacfddb7e9795d2b04aeff44b33525d0917a4a4bb42a92b30df838388ab01fba5fa8bf55adab004b0a51f966879dc15a3443736251edc9e49c2de37d6820", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86e73cc58e296716230ee3c50671cfaa165ed9a99266358c6cbd3fd9175637bcbfe9f097d4d052560fa7b0daaeb8a351e8f91f5d591b6d6736dd2bc6ec2389df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20803149617024abda13e0b307c1b16ae7389950677823fea45b8eceaadcfaf1800c361e899b386da8aec3661ee8932622ff53156d86d4e119020bcb3645b61d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4ef3025762925dbdbf0690b3af8aa5e36bdbb931e609891453130fdbd77afdbf83dfa4e212457732b05bb26c9187a48940b37cc123e6ea82fa64451c0f7e99"} build_url:https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/ShMLYSw4Q9KKpfxO185Qwg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bbebacfddb7e9795d2b04aeff44b33525d0917a4a4bb42a92b30df838388ab01fba5fa8bf55adab004b0a51f966879dc15a3443736251edc9e49c2de37d6820", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86e73cc58e296716230ee3c50671cfaa165ed9a99266358c6cbd3fd9175637bcbfe9f097d4d052560fa7b0daaeb8a351e8f91f5d591b6d6736dd2bc6ec2389df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20803149617024abda13e0b307c1b16ae7389950677823fea45b8eceaadcfaf1800c361e899b386da8aec3661ee8932622ff53156d86d4e119020bcb3645b61d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b4ef3025762925dbdbf0690b3af8aa5e36bdbb931e609891453130fdbd77afdbf83dfa4e212457732b05bb26c9187a48940b37cc123e6ea82fa64451c0f7e99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:29.691722) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:29.692084) ========= 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.ZzdcNk6CuT/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.X1DWpGQfAg/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 14:54:29.740701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:54:29.741042) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 14:54:29.741387) ========= ========= Total master_lag: 0.18 =========