builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0261 starttime: 1492418052.57 results: success (0) buildid: 20170417004005 builduid: b49714ccaf7a4327b6baee7fc9b58aa0 revision: 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:12.569895) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:12.570435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:12.570938) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:12.597698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:12.598027) ========= 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.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-17 01:34:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-04-17 01:34:13 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.455551 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:13.109061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:13.109378) ========= 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.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.034691 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:13.169585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 01:34:13.170273) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-17 01:34:13,281 truncating revision to first 12 chars 2017-04-17 01:34:13,281 Setting DEBUG logging. 2017-04-17 01:34:13,281 attempt 1/10 2017-04-17 01:34:13,281 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/44f6061a5dc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 01:34:14,009 unpacking tar archive at: mozilla-aurora-44f6061a5dc6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.006327 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 01:34:14.202788) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:14.203122) ========= script_repo_revision: 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:14.203529) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:14.203833) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 01:34:14.223625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 01:34:14.223965) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:34:14 INFO - MultiFileLogger online at 20170417 01:34:14 in /builds/slave/test 01:34:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:34:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:34:14 INFO - {'append_to_log': False, 01:34:14 INFO - 'base_work_dir': '/builds/slave/test', 01:34:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:34:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:34:14 INFO - 'branch': 'Mozilla-Aurora', 01:34:14 INFO - 'buildbot_json_path': 'buildprops.json', 01:34:14 INFO - 'config_files': ('talos/mac_config.py',), 01:34:14 INFO - 'default_actions': ('clobber', 01:34:14 INFO - 'read-buildbot-config', 01:34:14 INFO - 'download-and-extract', 01:34:14 INFO - 'populate-webroot', 01:34:14 INFO - 'create-virtualenv', 01:34:14 INFO - 'install', 01:34:14 INFO - 'run-tests'), 01:34:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:34:14 INFO - 'download_minidump_stackwalk': True, 01:34:14 INFO - 'download_symbols': 'ondemand', 01:34:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:34:14 INFO - 'tooltool.py': '/tools/tooltool.py', 01:34:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:34:14 INFO - '/tools/misc-python/virtualenv.py')}, 01:34:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:34:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:34:14 INFO - 'gecko_profile': False, 01:34:14 INFO - 'gecko_profile_interval': 0, 01:34:14 INFO - 'installer_path': 'installer.exe', 01:34:14 INFO - 'log_level': 'info', 01:34:14 INFO - 'log_name': 'talos', 01:34:14 INFO - 'log_to_console': True, 01:34:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:34:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:34:14 INFO - 'opt_config_files': (), 01:34:14 INFO - 'pip_index': False, 01:34:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:34:14 INFO - 'cmd': ('bash', 01:34:14 INFO - '-c', 01:34:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:34:14 INFO - 'enabled': True, 01:34:14 INFO - 'halt_on_failure': False, 01:34:14 INFO - 'name': 'check_screen_resolution'},), 01:34:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:34:14 INFO - 'cmd': ('bash', 01:34:14 INFO - '-c', 01:34:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:34:14 INFO - 'enabled': True, 01:34:14 INFO - 'halt_on_failure': False, 01:34:14 INFO - 'name': 'check_screen_resolution'},), 01:34:14 INFO - 'run_cmd_checks_enabled': True, 01:34:14 INFO - 'suite': 'dromaeojs-e10s', 01:34:14 INFO - 'system_bits': '32', 01:34:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:34:14 INFO - 'title': 't-yosemite-r7-0261', 01:34:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:34:14 INFO - 'use_talos_json': True, 01:34:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:34:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:34:14 INFO - 'work_dir': 'build'} 01:34:14 INFO - [mozharness: 2017-04-17 08:34:14.441454Z] Running clobber step. 01:34:14 INFO - Running pre-action listener: _resource_record_pre_action 01:34:14 INFO - Running main action method: clobber 01:34:14 INFO - rmtree: /builds/slave/test/build 01:34:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:34:16 INFO - Running post-action listener: _resource_record_post_action 01:34:16 INFO - [mozharness: 2017-04-17 08:34:16.876718Z] Finished clobber step (success) 01:34:16 INFO - [mozharness: 2017-04-17 08:34:16.876865Z] Running read-buildbot-config step. 01:34:16 INFO - Running pre-action listener: _resource_record_pre_action 01:34:16 INFO - Running main action method: read_buildbot_config 01:34:16 INFO - Using buildbot properties: 01:34:16 INFO - { 01:34:16 INFO - "project": "", 01:34:16 INFO - "product": "firefox", 01:34:16 INFO - "script_repo_revision": "production", 01:34:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 01:34:16 INFO - "repository": "", 01:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 01:34:16 INFO - "buildid": "20170417004005", 01:34:16 INFO - "stage_platform": "macosx64", 01:34:16 INFO - "basedir": "/builds/slave/test", 01:34:16 INFO - "nightly_build": "True", 01:34:16 INFO - "buildnumber": 41, 01:34:16 INFO - "slavename": "t-yosemite-r7-0261", 01:34:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:34:16 INFO - "platform": "yosemite_r7", 01:34:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:34:16 INFO - "branch": "mozilla-aurora", 01:34:16 INFO - "repo_path": "releases/mozilla-aurora", 01:34:16 INFO - "revision": "44f6061a5dc639a61fdd43cd88b03bba475b1cb0", 01:34:16 INFO - "pgo_build": "False", 01:34:16 INFO - "builduid": "b49714ccaf7a4327b6baee7fc9b58aa0", 01:34:16 INFO - "slavebuilddir": "test" 01:34:16 INFO - } 01:34:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:34:16 INFO - Running post-action listener: _resource_record_post_action 01:34:16 INFO - [mozharness: 2017-04-17 08:34:16.881022Z] Finished read-buildbot-config step (success) 01:34:16 INFO - [mozharness: 2017-04-17 08:34:16.881164Z] Running download-and-extract step. 01:34:16 INFO - Running pre-action listener: _resource_record_pre_action 01:34:16 INFO - Running main action method: download_and_extract 01:34:16 INFO - mkdir: /builds/slave/test/build/tests 01:34:16 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')]} 01:34:16 INFO - trying https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:34:17 INFO - Downloaded 1199 bytes. 01:34:17 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:34:17 INFO - Using the following test package requirements: 01:34:17 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:34:17 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:34:17 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:34:17 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'jsshell-mac.zip'], 01:34:17 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:34:17 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:34:17 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:34:17 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:34:17 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:34:17 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:34:17 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:34:17 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:34:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:34:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:34:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:34:19 INFO - Expected file size: 51793131 01:34:19 INFO - Obtained file size: 51793131 01:34:35 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:34:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:34:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:34:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:34:37 INFO - Expected file size: 51793131 01:34:37 INFO - Obtained file size: 51793131 01:34:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:34:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:34:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:34:54 INFO - Expected file size: 14404424 01:34:54 INFO - Obtained file size: 14404424 01:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:34:54 INFO - trying https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:34:56 INFO - Downloaded 74762531 bytes. 01:34:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:56 INFO - mkdir: /builds/slave/test/properties 01:34:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:34:56 INFO - Writing to file /builds/slave/test/properties/build_url 01:34:56 INFO - Contents: 01:34:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:34:57 INFO - Running post-action listener: _resource_record_post_action 01:34:57 INFO - Running post-action listener: set_extra_try_arguments 01:34:57 INFO - [mozharness: 2017-04-17 08:34:57.443753Z] Finished download-and-extract step (success) 01:34:57 INFO - [mozharness: 2017-04-17 08:34:57.443874Z] Running populate-webroot step. 01:34:57 INFO - Running pre-action listener: _resource_record_pre_action 01:34:57 INFO - Running main action method: populate_webroot 01:34:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:34:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:34:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:34:57 INFO - '--tppagecycles', 01:34:57 INFO - '7'], 01:34:57 INFO - 'tests': ['tsvgm']}}, 01:34:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:34:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:34:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:34:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:34:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:34:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['damp', 'tps']}, 01:34:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'tests': ['damp', 'tps']}, 01:34:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['dromaeo_dom']}, 01:34:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:34:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['basic_compositor_video']}, 01:34:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:34:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'other-e10s': {'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['a11yr', 01:34:57 INFO - 'ts_paint', 01:34:57 INFO - 'tpaint', 01:34:57 INFO - 'sessionrestore', 01:34:57 INFO - 'sessionrestore_no_auto_restore', 01:34:57 INFO - 'tabpaint']}, 01:34:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['tsvgx', 01:34:57 INFO - 'tsvgr_opacity', 01:34:57 INFO - 'tart', 01:34:57 INFO - 'tscrollx', 01:34:57 INFO - 'cart', 01:34:57 INFO - 'tsvg_static']}, 01:34:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:34:57 INFO - 'tsvgr_opacity', 01:34:57 INFO - 'tart', 01:34:57 INFO - 'tscrollx', 01:34:57 INFO - 'cart', 01:34:57 INFO - 'tsvg_static']}, 01:34:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'talos_options': ['--disable-e10s'], 01:34:57 INFO - 'tests': ['tp5o']}, 01:34:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:34:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'talos_options': ['--disable-e10s', 01:34:57 INFO - '--xperf_path', 01:34:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:57 INFO - 'tests': ['tp5n']}, 01:34:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:34:57 INFO - 'talos_options': ['--xperf_path', 01:34:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:34:57 INFO - 'tests': ['tp5n']}}, 01:34:57 INFO - 'talos.zip': {'path': '', 01:34:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:34:57 INFO - Running post-action listener: _resource_record_post_action 01:34:57 INFO - [mozharness: 2017-04-17 08:34:57.461950Z] Finished populate-webroot step (success) 01:34:57 INFO - [mozharness: 2017-04-17 08:34:57.462055Z] Running create-virtualenv step. 01:34:57 INFO - Running pre-action listener: _resource_record_pre_action 01:34:57 INFO - Running main action method: create_virtualenv 01:34:57 INFO - Creating virtualenv /builds/slave/test/build/venv 01:34:57 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 01:34:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:34:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:34:57 INFO - Using real prefix '/tools/python27' 01:34:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:34:58 INFO - Installing distribute.............................................................................................................................................................................................done. 01:35:01 INFO - Installing pip.................done. 01:35:01 INFO - Return code: 0 01:35:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:35:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:35:01 INFO - Reading from file tmpfile_stdout 01:35:01 INFO - Output received: 01:35:01 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) 01:35:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:35:01 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')]} 01:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:01 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')]} 01:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:01 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:01 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 01:35:01 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 01:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:01 INFO - 'HOME': '/Users/cltbld', 01:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:01 INFO - 'LOGNAME': 'cltbld', 01:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:01 INFO - 'MOZ_NO_REMOTE': '1', 01:35:01 INFO - 'NO_EM_RESTART': '1', 01:35:01 INFO - 'PAGER': '/bin/cat', 01:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:01 INFO - 'PWD': '/builds/slave/test', 01:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:01 INFO - 'SHELL': '/bin/bash', 01:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:01 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:01 INFO - 'USER': 'cltbld', 01:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:01 INFO - 'XPC_FLAGS': '0x0', 01:35:01 INFO - 'XPC_SERVICE_NAME': '0', 01:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:01 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 01:35:01 INFO - Cleaning up... 01:35:01 INFO - Return code: 0 01:35:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:35:01 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')]} 01:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:01 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')]} 01:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:01 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:01 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 01:35:01 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 01:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:01 INFO - 'HOME': '/Users/cltbld', 01:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:01 INFO - 'LOGNAME': 'cltbld', 01:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:01 INFO - 'MOZ_NO_REMOTE': '1', 01:35:01 INFO - 'NO_EM_RESTART': '1', 01:35:01 INFO - 'PAGER': '/bin/cat', 01:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:01 INFO - 'PWD': '/builds/slave/test', 01:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:01 INFO - 'SHELL': '/bin/bash', 01:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:01 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:01 INFO - 'USER': 'cltbld', 01:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:01 INFO - 'XPC_FLAGS': '0x0', 01:35:01 INFO - 'XPC_SERVICE_NAME': '0', 01:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:01 INFO - Downloading/unpacking psutil>=3.1.1 01:35: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 01:35: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 01:35: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 01:35: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 01:35: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 01:35: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 01:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:35:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:35:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:35:05 INFO - Installing collected packages: psutil 01:35:05 INFO - Running setup.py install for psutil 01:35:06 INFO - building 'psutil._psutil_osx' extension 01:35:06 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 01:35:06 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 01:35:06 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 01:35:06 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 01:35:06 INFO - building 'psutil._psutil_posix' extension 01:35:06 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 01:35:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:35:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:35:06 INFO - ^ 01:35:06 INFO - 1 warning generated. 01:35:06 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 01:35:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:35:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:35:07 INFO - Successfully installed psutil 01:35:07 INFO - Cleaning up... 01:35:07 INFO - Return code: 0 01:35:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:35: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')]} 01:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35: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')]} 01:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:07 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:07 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 01:35:07 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 01:35:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:07 INFO - 'HOME': '/Users/cltbld', 01:35:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:07 INFO - 'LOGNAME': 'cltbld', 01:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:07 INFO - 'MOZ_NO_REMOTE': '1', 01:35:07 INFO - 'NO_EM_RESTART': '1', 01:35:07 INFO - 'PAGER': '/bin/cat', 01:35:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:07 INFO - 'PWD': '/builds/slave/test', 01:35:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:07 INFO - 'SHELL': '/bin/bash', 01:35:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:07 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:07 INFO - 'USER': 'cltbld', 01:35:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:07 INFO - 'XPC_FLAGS': '0x0', 01:35:07 INFO - 'XPC_SERVICE_NAME': '0', 01:35:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:35:10 INFO - Installing collected packages: mozsystemmonitor 01:35:10 INFO - Running setup.py install for mozsystemmonitor 01:35:10 INFO - Successfully installed mozsystemmonitor 01:35:10 INFO - Cleaning up... 01:35:10 INFO - Return code: 0 01:35:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:35: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')]} 01:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35: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')]} 01:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:10 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:10 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 01:35:10 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 01:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:10 INFO - 'HOME': '/Users/cltbld', 01:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:10 INFO - 'LOGNAME': 'cltbld', 01:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:10 INFO - 'MOZ_NO_REMOTE': '1', 01:35:10 INFO - 'NO_EM_RESTART': '1', 01:35:10 INFO - 'PAGER': '/bin/cat', 01:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:10 INFO - 'PWD': '/builds/slave/test', 01:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:10 INFO - 'SHELL': '/bin/bash', 01:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:10 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:10 INFO - 'USER': 'cltbld', 01:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:10 INFO - 'XPC_FLAGS': '0x0', 01:35:10 INFO - 'XPC_SERVICE_NAME': '0', 01:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:10 INFO - Downloading/unpacking jsonschema==2.5.1 01:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:35:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 INFO - Downloading functools32-3.2.3-2.tar.gz 01:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:35:13 INFO - warning: no files found matching '*.txt' 01:35:13 INFO - no previously-included directories found matching 'build' 01:35:13 INFO - no previously-included directories found matching 'dist' 01:35:13 INFO - no previously-included directories found matching '.git*' 01:35:13 INFO - Installing collected packages: jsonschema, functools32 01:35:13 INFO - Running setup.py install for functools32 01:35:13 INFO - warning: no files found matching '*.txt' 01:35:13 INFO - no previously-included directories found matching 'build' 01:35:13 INFO - no previously-included directories found matching 'dist' 01:35:13 INFO - no previously-included directories found matching '.git*' 01:35:13 INFO - Successfully installed jsonschema functools32 01:35:13 INFO - Cleaning up... 01:35:13 INFO - Return code: 0 01:35:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:35:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:35:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:13 INFO - 'HOME': '/Users/cltbld', 01:35:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:13 INFO - 'LOGNAME': 'cltbld', 01:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:13 INFO - 'MOZ_NO_REMOTE': '1', 01:35:13 INFO - 'NO_EM_RESTART': '1', 01:35:13 INFO - 'PAGER': '/bin/cat', 01:35:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:13 INFO - 'PWD': '/builds/slave/test', 01:35:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:13 INFO - 'SHELL': '/bin/bash', 01:35:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:13 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:13 INFO - 'USER': 'cltbld', 01:35:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:13 INFO - 'XPC_FLAGS': '0x0', 01:35:13 INFO - 'XPC_SERVICE_NAME': '0', 01:35:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:35:14 INFO - Cleaning up... 01:35:14 INFO - Return code: 0 01:35:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:35: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')]} 01:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35: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')]} 01:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:14 INFO - 'HOME': '/Users/cltbld', 01:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:14 INFO - 'LOGNAME': 'cltbld', 01:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:14 INFO - 'MOZ_NO_REMOTE': '1', 01:35:14 INFO - 'NO_EM_RESTART': '1', 01:35:14 INFO - 'PAGER': '/bin/cat', 01:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:14 INFO - 'PWD': '/builds/slave/test', 01:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:14 INFO - 'SHELL': '/bin/bash', 01:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:14 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:14 INFO - 'USER': 'cltbld', 01:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:14 INFO - 'XPC_FLAGS': '0x0', 01:35:14 INFO - 'XPC_SERVICE_NAME': '0', 01:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:14 INFO - Downloading/unpacking blobuploader==1.2.4 01:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:16 INFO - Downloading blobuploader-1.2.4.tar.gz 01:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:35:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:35:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:18 INFO - Downloading docopt-0.6.1.tar.gz 01:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:35:18 INFO - Installing collected packages: blobuploader, requests, docopt 01:35:18 INFO - Running setup.py install for blobuploader 01:35:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:35:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:35:18 INFO - Running setup.py install for requests 01:35:19 INFO - Running setup.py install for docopt 01:35:19 INFO - Successfully installed blobuploader requests docopt 01:35:19 INFO - Cleaning up... 01:35:19 INFO - Return code: 0 01:35:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:19 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:19 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 01:35:19 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 01:35:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:19 INFO - 'HOME': '/Users/cltbld', 01:35:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:19 INFO - 'LOGNAME': 'cltbld', 01:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:19 INFO - 'MOZ_NO_REMOTE': '1', 01:35:19 INFO - 'NO_EM_RESTART': '1', 01:35:19 INFO - 'PAGER': '/bin/cat', 01:35:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:19 INFO - 'PWD': '/builds/slave/test', 01:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:19 INFO - 'SHELL': '/bin/bash', 01:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:19 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:19 INFO - 'USER': 'cltbld', 01:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:19 INFO - 'XPC_FLAGS': '0x0', 01:35:19 INFO - 'XPC_SERVICE_NAME': '0', 01:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:35:19 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-4RVnHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:35:19 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-HWLCKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:35:19 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-JUV1mV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:35:19 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-WcUwy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:35:19 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-1ju60F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-HNKf25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-_OOPAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-LSUBHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Dxti35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-GsGKhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-QqVAst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-9H1fKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-cYdd1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:35:20 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Ilzbnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-_IklRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:35:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-nsruhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:35:21 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-Ui6ZVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:35:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:35:21 INFO - Running setup.py install for manifestparser 01:35:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:35:21 INFO - Running setup.py install for mozcrash 01:35:21 INFO - Running setup.py install for mozdebug 01:35:21 INFO - Running setup.py install for mozdevice 01:35:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:35:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:35:21 INFO - Running setup.py install for mozfile 01:35:22 INFO - Running setup.py install for mozhttpd 01:35:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for mozinfo 01:35:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for mozInstall 01:35:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for mozleak 01:35:22 INFO - Running setup.py install for mozlog 01:35:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for moznetwork 01:35:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for mozprocess 01:35:23 INFO - Running setup.py install for mozprofile 01:35:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozrunner 01:35:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozscreenshot 01:35:23 INFO - Running setup.py install for moztest 01:35:23 INFO - Running setup.py install for mozversion 01:35:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:35:23 INFO - Cleaning up... 01:35:23 INFO - Return code: 0 01:35:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:23 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:23 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 01:35:23 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 01:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:23 INFO - 'HOME': '/Users/cltbld', 01:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:23 INFO - 'LOGNAME': 'cltbld', 01:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:23 INFO - 'MOZ_NO_REMOTE': '1', 01:35:23 INFO - 'NO_EM_RESTART': '1', 01:35:23 INFO - 'PAGER': '/bin/cat', 01:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:23 INFO - 'PWD': '/builds/slave/test', 01:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:23 INFO - 'SHELL': '/bin/bash', 01:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:23 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:23 INFO - 'USER': 'cltbld', 01:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:23 INFO - 'XPC_FLAGS': '0x0', 01:35:23 INFO - 'XPC_SERVICE_NAME': '0', 01:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-LEUohb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-iYkbXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-l1qG0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-77vYjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-FwGIfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-8T3_Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-h7MNVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:35:24 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)) 01:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:35:24 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-4Iffbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-B1BfVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-WDXKiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-ArSjPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-pJ_T0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-KRPVDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-OOXRMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-a8CUJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-I8QDOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:35:25 INFO - Running setup.py (path:/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/pip-pBnUuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:35:26 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)) 01:35:26 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)) 01:35:26 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)) 01:35:26 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)) 01:35:26 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)) 01:35:26 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)) 01:35:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:28 INFO - Downloading blessings-1.6.tar.gz 01:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:35:28 INFO - Installing collected packages: blessings 01:35:28 INFO - Running setup.py install for blessings 01:35:28 INFO - Successfully installed blessings 01:35:28 INFO - Cleaning up... 01:35:29 INFO - Return code: 0 01:35:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:29 INFO - Reading from file tmpfile_stdout 01:35:29 INFO - Current package versions: 01:35:29 INFO - blessings == 1.6 01:35:29 INFO - blobuploader == 1.2.4 01:35:29 INFO - docopt == 0.6.1 01:35:29 INFO - functools32 == 3.2.3-2 01:35:29 INFO - jsonschema == 2.5.1 01:35:29 INFO - manifestparser == 1.1 01:35:29 INFO - mozInstall == 1.12 01:35:29 INFO - mozcrash == 1.0 01:35:29 INFO - mozdebug == 0.1 01:35:29 INFO - mozdevice == 0.49 01:35:29 INFO - mozfile == 1.2 01:35:29 INFO - mozhttpd == 0.7 01:35:29 INFO - mozinfo == 0.9 01:35:29 INFO - mozleak == 0.1 01:35:29 INFO - mozlog == 3.4 01:35:29 INFO - moznetwork == 0.27 01:35:29 INFO - mozprocess == 0.25 01:35:29 INFO - mozprofile == 0.28 01:35:29 INFO - mozrunner == 6.13 01:35:29 INFO - mozscreenshot == 0.1 01:35:29 INFO - mozsystemmonitor == 0.3 01:35:29 INFO - moztest == 0.8 01:35:29 INFO - mozversion == 1.4 01:35:29 INFO - psutil == 3.1.1 01:35:29 INFO - requests == 1.2.3 01:35:29 INFO - wsgiref == 0.1.2 01:35:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:29 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')]} 01:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:29 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')]} 01:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:29 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:29 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 01:35:29 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 01:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:29 INFO - 'HOME': '/Users/cltbld', 01:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:29 INFO - 'LOGNAME': 'cltbld', 01:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:29 INFO - 'MOZ_NO_REMOTE': '1', 01:35:29 INFO - 'NO_EM_RESTART': '1', 01:35:29 INFO - 'PAGER': '/bin/cat', 01:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:29 INFO - 'PWD': '/builds/slave/test', 01:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:29 INFO - 'SHELL': '/bin/bash', 01:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:29 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:29 INFO - 'USER': 'cltbld', 01:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:29 INFO - 'XPC_FLAGS': '0x0', 01:35:29 INFO - 'XPC_SERVICE_NAME': '0', 01:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 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)) 01:35:29 INFO - Cleaning up... 01:35:29 INFO - Return code: 0 01:35:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:35:29 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')]} 01:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:29 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')]} 01:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:29 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 0x10b96db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b970768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec084f6610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, '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.5ioKecm5TN/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.fw7buE50g3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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 01:35:29 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 01:35:29 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 01:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:29 INFO - 'HOME': '/Users/cltbld', 01:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:29 INFO - 'LOGNAME': 'cltbld', 01:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:29 INFO - 'MOZ_NO_REMOTE': '1', 01:35:29 INFO - 'NO_EM_RESTART': '1', 01:35:29 INFO - 'PAGER': '/bin/cat', 01:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:29 INFO - 'PWD': '/builds/slave/test', 01:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:29 INFO - 'SHELL': '/bin/bash', 01:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:29 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:29 INFO - 'USER': 'cltbld', 01:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:29 INFO - 'XPC_FLAGS': '0x0', 01:35:29 INFO - 'XPC_SERVICE_NAME': '0', 01:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:35:29 INFO - Cleaning up... 01:35:29 INFO - Return code: 0 01:35:29 INFO - Running post-action listener: _resource_record_post_action 01:35:29 INFO - Running post-action listener: _start_resource_monitoring 01:35:29 INFO - Starting resource monitoring. 01:35:29 INFO - [mozharness: 2017-04-17 08:35:29.892914Z] Finished create-virtualenv step (success) 01:35:29 INFO - [mozharness: 2017-04-17 08:35:29.893839Z] Running install step. 01:35:29 INFO - Running pre-action listener: _resource_record_pre_action 01:35:29 INFO - Running main action method: install 01:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:30 INFO - Reading from file tmpfile_stdout 01:35:30 INFO - Detecting whether we're running mozinstall >=1.0... 01:35:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:35:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:35:30 INFO - Reading from file tmpfile_stdout 01:35:30 INFO - Output received: 01:35:30 INFO - Usage: mozinstall [options] installer 01:35:30 INFO - Options: 01:35:30 INFO - -h, --help show this help message and exit 01:35:30 INFO - -d DEST, --destination=DEST 01:35:30 INFO - Directory to install application into. [default: 01:35:30 INFO - "/builds/slave/test"] 01:35:30 INFO - --app=APP Application being installed. [default: firefox] 01:35:30 INFO - mkdir: /builds/slave/test/build/application 01:35:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:35:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:35:50 INFO - Reading from file tmpfile_stdout 01:35:50 INFO - Output received: 01:35:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:35:50 INFO - Running post-action listener: _resource_record_post_action 01:35:50 INFO - [mozharness: 2017-04-17 08:35:50.624854Z] Finished install step (success) 01:35:50 INFO - [mozharness: 2017-04-17 08:35:50.625020Z] Running run-tests step. 01:35:50 INFO - Running pre-action listener: _resource_record_pre_action 01:35:50 INFO - Running main action method: run_tests 01:35:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:35:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:35:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:35:50 INFO - 2017-04-17 01:35:50.638 screenresolution[1627:8510] starting screenresolution argv=screenresolution get 01:35:50 INFO - 2017-04-17 01:35:50.640 screenresolution[1627:8510] Display 0: 1600x1200x32@60 01:35:50 INFO - 2017-04-17 01:35:50.646 screenresolution[1628:8513] starting screenresolution argv=screenresolution list 01:35:50 INFO - Available Modes on Display 0 01:35:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:35:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:35:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:35:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:35:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:35:50 INFO - Intel Iris: 01:35:50 INFO - Chipset Model: Intel Iris 01:35:50 INFO - Type: GPU 01:35:50 INFO - Bus: Built-In 01:35:50 INFO - VRAM (Dynamic, Max): 1536 MB 01:35:50 INFO - Vendor: Intel (0x8086) 01:35:50 INFO - Device ID: 0x0a2e 01:35:50 INFO - Revision ID: 0x0009 01:35:50 INFO - Displays: 01:35:50 INFO - Display: 01:35:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:35:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:35:50 INFO - Display Serial Number: 1600x1200 60 01:35:50 INFO - Main Display: Yes 01:35:50 INFO - Mirror: Off 01:35:50 INFO - Online: Yes 01:35:50 INFO - Rotation: Supported 01:35:50 INFO - Return code: 0 01:35:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:35:50 INFO - Python 2.7.3 01:35:50 INFO - Return code: 0 01:35:50 INFO - grabbing minidump binary from tooltool 01:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:50 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 0x10b74bd30>, '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 0x10b672db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b740be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:35:50 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 01:35:50 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 01:35:50 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 01:35:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:35:51 INFO - Return code: 0 01:35:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:35:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:35:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:35:51 INFO - ENV: RUST_BACKTRACE is now 1 01:35:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:35:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:35:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 01:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0261 --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 01:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5ioKecm5TN/Render', 01:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:51 INFO - 'HOME': '/Users/cltbld', 01:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:51 INFO - 'LOGNAME': 'cltbld', 01:35:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:35:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:51 INFO - 'MOZ_NO_REMOTE': '1', 01:35:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:35:51 INFO - 'NO_EM_RESTART': '1', 01:35:51 INFO - 'PAGER': '/bin/cat', 01:35:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:51 INFO - 'PWD': '/builds/slave/test', 01:35:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:51 INFO - 'RUST_BACKTRACE': '1', 01:35:51 INFO - 'SHELL': '/bin/bash', 01:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fw7buE50g3/Listeners', 01:35:51 INFO - 'TMPDIR': '/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/', 01:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:51 INFO - 'USER': 'cltbld', 01:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:51 INFO - 'XPC_FLAGS': '0x0', 01:35:51 INFO - 'XPC_SERVICE_NAME': '0', 01:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0261', '--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 01:35:51 INFO - mozversion application_buildid: 20170417004005 01:35:51 INFO - mozversion application_changeset: 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 01:35:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:35:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:35:51 INFO - mozversion application_name: Firefox 01:35:51 INFO - mozversion application_remotingname: firefox-dev 01:35:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:35:51 INFO - mozversion application_vendor: Mozilla 01:35:51 INFO - mozversion application_version: 54.0a2 01:35:51 INFO - mozversion platform_buildid: 20170417004005 01:35:51 INFO - mozversion platform_changeset: 44f6061a5dc639a61fdd43cd88b03bba475b1cb0 01:35:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:35:51 INFO - mozversion platform_version: 54.0a2 01:35:51 INFO - using testdate: 1492418151 01:35:51 INFO - actual date: 1492418151 01:35:51 INFO - starting webserver on 'localhost:49235' 01:35:51 INFO - SUITE-START | Running 2 tests 01:35:51 INFO - TEST-START | dromaeo_css 01:35:51 INFO - operating with platform_type : mac_ 01:35:51 INFO - Initialising browser for dromaeo_css test... 01:35:51 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpK8rAmI/profile http://localhost:49235/getInfo.html) 01:35:54 INFO - PROCESS | 1638 | 2017-04-17 01:35:54.299 plugin-container[1642:8721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 01:35:54 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:35:54 INFO - PROCESS | 1638 | 2017-04-17 01:35:54.299 plugin-container[1642:8721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 01:35:54 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:35:54 INFO - PROCESS | 1638 | 2017-04-17 01:35:54.299 plugin-container[1642:8721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:35:54 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 01:35:54 INFO - PROCESS | 1638 | colorDepth:24 01:35:54 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 01:35:54 INFO - PROCESS | 1638 | __metrics 01:35:54 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 01:35:55 INFO - TEST-INFO | 1638: exit 0 01:35:55 INFO - Browser initialized. 01:35:55 INFO - Running cycle 1/1 for dromaeo_css test... 01:35:55 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpK8rAmI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:35:57 INFO - PROCESS | 1644 | 2017-04-17 01:35:57.892 plugin-container[1645:8977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 01:35:57 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:35:57 INFO - PROCESS | 1644 | 2017-04-17 01:35:57.893 plugin-container[1645:8977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 01:35:57 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:35:57 INFO - PROCESS | 1644 | 2017-04-17 01:35:57.893 plugin-container[1645:8977] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:36:07 INFO - PROCESS | 1644 | RSS: Main: 221126656 01:36:07 INFO - PROCESS | 1644 | 01:39:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 01:39:12 INFO - PROCESS | 1644 | RSS: Main: 238276608 01:39:12 INFO - PROCESS | 1644 | 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 01:40:55 INFO - PROCESS | 1644 | 01:40:55 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:55 INFO - PROCESS | 1644 | Full stack: 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 01:40:55 INFO - PROCESS | 1644 | 01:40:55 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:55 INFO - PROCESS | 1644 | Full stack: 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 01:40:55 INFO - PROCESS | 1644 | 01:40:55 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:55 INFO - PROCESS | 1644 | Full stack: 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | ************************* 01:40:55 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 01:40:55 INFO - PROCESS | 1644 | 01:40:55 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:40:55 INFO - PROCESS | 1644 | Full stack: 01:40:55 INFO - PROCESS | 1644 | ************************* 01:42:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 01:42:15 INFO - PROCESS | 1644 | RSS: Main: 248229888 01:42:15 INFO - PROCESS | 1644 | 01:45:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 01:45:18 INFO - PROCESS | 1644 | RSS: Main: 255606784 01:45:18 INFO - PROCESS | 1644 | 01:48:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 01:48:20 INFO - PROCESS | 1644 | RSS: Main: 251715584 01:48:20 INFO - PROCESS | 1644 | 01:51:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 01:51:24 INFO - PROCESS | 1644 | RSS: Main: 256741376 01:51:24 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 01:54:27 INFO - PROCESS | 1644 | RSS: Main: 255205376 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | __start_tp_report 01:54:27 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 01:54:27 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 01:54:27 INFO - PROCESS | 1644 | |i|pagename|runs| 01:54:27 INFO - PROCESS | 1644 | |0;dojo.html;3441.0709108540696;3462.4113900636894;3488.127968007998;3496.667816557427;3502.247016891368;6006.579539432241;6020.939790602092;6027.5479339049525;6109.389061093889;6129.509639228862;6024.126501657257;6050.576459647827;6065.848353791157;6066.605670631406;6074.392560743923;5374.247605335257;5389.191621256823;5440.782368705267;5502.064649009658;5506.476884695968;4638.4433867935895;5524.392316845144;5524.723763811813;5529.944700553004;5571.247881535981;5287.365516138049;5292.206169074631;5452.836414907559;5494.203340515623;5506.256655351536;5407.242985981966;5416.160495123262;5435.646682965595;5488.4785945335125;5489.368722596912;5307.336582927134;5419.512243898029;5425.674459532401;5442.074847275972;5481;5813.157092221646;6057.969710151465;6081.118237855486;6111.847203819895;6122.755089796403;6204.441600255954;6219.09823075656;6238.095476155977;6259.499240060784;6260;6473.93526064738;6476.6114033158165;6483;6483.7730679425995;6494.993276042221;6102.481289090389;6149.446549810449;6160.907586386208;6166.260048794128;6181.752729890754;6243.7814676486105;6304.117423560613;6480.546361754727;6590.373914478117;6612.90080648781;6257.78097766576;6267.4986001119805;6288.496920246369;6297.464715499143;6312.589681670676;6357.809265722036;6379.138816259839;6412.935870641327;6427.971524556048;6438.871222575616;6139.798803208702;6220.533459990428;6265.780697675651;6331.96834015827;6338.239411270585;6208.875822483525;6234.657093859882;6269.153664255358;6329.588576742497;6350.904736428957;4476.664250181249;4536.500984891659;5048.939722658283;5161.845144645667;5165.889553401199;4604.677672562889;4616.538346165356;4620.0990806793025;4639.095376401569;4652.813887444464;5870.295564532284;5962.22491076166;6089.208402907594;6100.664463454553;6113.84715382119;6453.032045193258;6460.257070436866;6482.384173503509;6485.092087107809;6499.512536559777;5603.635763675348;5607.018771714934;5949.524038076944;6004.579679422486;6074.331823499411;4915.729634870045;5726.770929162787;5793.623414478046;5803.796867109715;5804;6689.431398331197;6735.663216839041;6735.966320168466;6742.6291553965;6750.72997080131;6139.140520327069;6207.410296021871;6459.580127291711;6464.191976003;6474.5791523550815;6417.518686098468;6540.934590654033;6568.441682457044;6583.967080164569;6614.140161778938;6270.184875966095;6280.685965701605;6283.183186185766;6290.622562646256;6292.244930608356;6244.125822384961;6330.113784070142;6364.80905572815;6378.266499352449;6381.106645069601;6134.773364742599;6161.722722477589;6192.59748116371;6193.783217587363;6203.844903877259;5657.4059723728415;5732.770689172553;6214.347493513116;6248.687565621792;6267.185265915402;6051.152838602688;6137.84655383619;6205.627662340094;6223.502119830312;6231.563790534619;6139.614800607318;6144.064619644976;6149.2313460817395;6170.259568851767;6240.344763799736;6208.348123447154;6208.441240288215;6213.37866213375;6221.564490485623;6265.467435268053 01:54:27 INFO - PROCESS | 1644 | |1;ext.html;717.7882524655226;799.9720009799659;820.7209548753425;944.2115833279212;944.7118628818216;37427.625723742756;38231.80884095579;39541.604583954155;39628.60371396289;39735;32470.83764581174;38932.22135557294;39185;39242.8037859811;39249.607503924955;7876.763697089083;9519.762005949837;10706.036456718894;10991.450427478634;11073.280236784583;10718.196135289845;10762.784744305109;10813.945930270338;10819.459027048617;10825.296355736851;10808.135349172047;10836.403997780119;10851.945740271327;10859.13126949846;10867.347959122439;10767.461626918663;10789.190810689151;10807.351558906452;10814.35113893161;10827.133829293676;10531.894681053245;10552.261341706084;10613.257072004963;10626.990435908576;10627.52176152075;33714.662853371396;34065.48901766475;34074.48888266678;34095.6590434095;34102.806401775815;3015.4270688569322;3650.9229777215787;3714.665680088778;3733.6826369758605;3776.924461510782;24950.12674556382;25143.119990800235;25199.748002520108;26004;26095.478090438086;2808.985955070232;2876.949913281658;2883.2503549077182;3099.767517436201;3110.1913502489288;338044.61955380376;340040;340663;341543;341872.2906385477;44368;44644.77677611591;44801.10397792091;44831.327530087074;44865.55134448679;45942.770286148356;46357.76821115873;46385.76807115943;46425.071498569836;46497.30254046192;11397.658070257905;13598.11612245201;13840.723185536233;13866.65333366674;13912.582622521139;11690.649280521598;11885.821712674317;11892;11896.762064758655;11905.761884762256;11714.414279286002;11863;11877.168598198043;11934.22427542207;12006.099542534344;5570.582206334541;5599.664020158803;5604.8318550443555;5605.523530499871;5616.6068375213345;1059.5126241928645;1101.3336931156696;1132.6715252576826;1145.7192987718063;1164.2768702320443;24129.793510324413;24731.876340618182;25035.499290014097;26448.338791530365;26938;1215.4408971873038;1251.9889665354835;1261.1613277170761;1268.9936550317188;1274.936253187337;13698.315084245747;13698.452061917611;13861.792073118912;13891.86108138926;13950.162990220417;473515;476002;476863;478360.21639783855;479149;44512;56439;56536.15195772066;56710.865782684115;57096.57258568569;1265.9936700316623;1287.8003909394058;1428.978565321521;1441.942322307096;1474.741920163967;29313.56029659514;30104.24739381533;30278.84860575683;30393;30510.932117375785;37356.439653404675;46766.53233467758;47023.294650579584;47170.52829471661;47217;403808;405251;405382;405599;405605;31798;31820.363592728478;31899;31903.36193276168;32012.839935801105;42927;43092.56907431011;43658.56341436545;43770.68687939367;44473.33290000588;29616.703832961393;29799.70200298056;30764.846175768977;30869.382612348076;31005.844970775;29136.417271654023;29308.413831723672;29517.409651807273;30130.246743830703;30260.84869575638;26099.347516312246;32251.87118450843;33971.30139509936;36207.456888146175;36277.818610906776;29118;29157;29469;29515.966941156956;29851;20596.27913023097;20973.596539207643;23653.763462365157;23675.881620592474;24103.758962410153 01:54:27 INFO - PROCESS | 1644 | |2;jquery.html;388.42125233402237;408.4710300161291;419.4526143382886;431.63311185492336;445.55889669227474;19503.60992780145;19603.607927841436;20024.399268021945;20706.689399658997;20707.068181931812;19526.804731952678;21534.89232553839;21602;22377.888110559423;22553.54892902141;6225.128482012522;6749.325067493236;6758.594484330932;6771.085903403052;6789.049533065375;6336.46140078094;6521.608703477783;6583.3087525809815;6592.439642630382;6604.405603495685;5883.882062408129;6507.44686701631;6584.407403333699;6585.703643336062;6602.504812139085;6155.01519756839;7532.887006694904;7677.347425468842;7705.306522412954;7720.30517253447;7225.783226503161;7359.264073592652;7410.073740782403;7411.85176296471;7446.515976461513;21090.683639745417;25660;25983.74016259851;26084.478310433686;26165.73834261671;2905.1139402482236;2909.156344660046;2945.1311863000465;3015.6833532478995;3069.754419646423;15290.235488225544;16164.434244801376;16428.917855410647;16681;16727.498175054767;2033.7559492860803;2061.2376418162553;2064.721262629556;2087.2068613926613;2099.737532808399;153649;153889;154533;175580.12209938996;182977;19286.517837053907;20102.894340811396;20255.4936126598;20269.9865006752;20317.695234571493;30852.845735771178;30919;30961.69038309588;31328.686713133033;31367.84316078405;10122.240831937634;10214.744631384276;10266.435346055889;10362.792744145076;10532.684019479428;8604.397692161614;8609.61256743448;8628.654853805903;8651;8763.737087887373;9536.141747242782;9622.230221582397;9651.227901767841;9701.805963880683;9761.316707830383;3658.920618417573;4192.853250136231;4297.39836422901;4303.4190384298345;4340;749.576489283564;900.783811885156;907.1019690506351;939.9342046056711;943.4480828715141;18081.819181808012;20174.697379539317;20694.17223311051;20890.791092088886;20918.372448826558;810.1776696652902;844.6494704697604;895.2882458445421;932.2961164320894;964.0889359555293;9299.95350023259;10390.688279351632;10401.323913945569;10912.127029837595;10917.344959302467;639402;777047;778726;784898;800822;164942;165119;168388;170057;174539.12730436266;1177.3348058347178;1218.865924748277;1271.771081205392;1293.3662505372488;1296.2028352563107;17974.01142937151;18255.634887302444;18514.074296285402;18542.907285463487;18803;50299.49700503094;65152.34847651463;65529.017064743115;66140;66533.3346666527;84116;93697;94444;95177.52411237899;96652;9788.119069283799;11177.776444471228;11346.602868899548;11706.239094459173;11830.763384732429;12607.49570017189;12690;12755.298458584866;12816.038797090441;13058.673533161747;9940.30417870771;10932.890671093506;11093.778124437627;11173.497192626513;11222.719432014264;10308.639197628047;10317.432541210306;10324.793504129726;10504.107150891954;10612.469376531297;11255.549778008788;11427.885721143015;11490;11509.597164099216;11782.705432364259;10194.337368071052;11300.830487542858;11473.340153197369;11515.19393642437;11517.136214783757;9123.366909344662;11188.776224475629;11236.606718765132;11302.869713028631;11450.026747726537 01:54:27 INFO - PROCESS | 1644 | |3;mootools.html;1420.9218492982884;1438.8345440193443;1464.8095747552816;1565.9138747368897;1586.4050980882168;10720.35677859329;11085.22403431758;11385.089192864572;11385.373804440753;11398.202125851194;10555.366677999325;10715.303505272172;10728.195385346111;10751.731206719836;10797.622083227088;2363.4918492524102;2401.819863510235;2419.0686585664557;2419.1049311754678;2423.5758742220128;2311.2257393773107;2377.1679912030736;2407.9759202407927;2411.4815314707334;2416.1060407649193;2312.167619656922;2379.9048038078454;2395.6406539019113;2407.831451798375;2421.4309637235324;2310.353101131686;2370.229675355508;2371.5968285391436;2376.916807911715;2377.6671266022686;2326.4649130699863;2371.312319427381;2372.323887692017;2375.002498950445;2385.7375688674233;11057.778844423146;11323.603673871457;11334.943325283402;11337.37644429556;11338.489767960462;5685.545156387479;5898.852528686774;5900.881982360329;5907.763689452417;5913;3449.810260435675;3552.0409489437707;3567.518385018015;3569.39320315547;3591.174029973121;1210.152892974921;1210.3645586067298;1248.1200753468784;1261.356708078877;1268.3975111821903;12471.127021108527;13773.517926872611;14516.491922782652;14805.259737013106;15289.694206115815;4536.56902594253;4575.702579332333;4723.881902952454;4729.763511824395;4749.525047495292;10249.487525623688;11558.364289964133;11807.291562506278;11847.992920601846;11895.345755983504;5103.5406813386235;5604.775808967595;5698;5790.189373487715;5792.7103644818435;5097.46476619957;5102.10713125209;5130.9230361544605;5162.690238585622;5214.374275086938;3788.5453745550712;3878.4182372643763;3880.428426487277;3936.2324346752107;3940.6847452203756;983.7638966647953;1020.7550187954837;1023.1865666794915;1028.9022542858415;1029.145808978546;917.6742256498846;918.8055527813815;936.6066252174103;944.5654998700537;988.5502096546054;6272.560920735596;6303.779367722108;6432.71052802625;6489.805305840832;6531.216254049545;927.4945154890601;978.5205249427871;980.8185485685171;993.2203220471895;1011.6105299459614;3901.8634347798256;3992;4056.1887622475624;4090.8159132839096;4113.745307681186;13091.672708182372;13451.798223026466;13470.123968589363;13587.660308492366;13726.245056521788;13419.127756695783;13453.394597243148;13645.863541364459;13679;13687;8559.22966932979;10065.043820837009;10244.48777561119;10245.385276883262;10281.971802819658;5424;6192.9337038508775;6487.578307410004;6528.118703974903;6623.205215374186;11040.889591103985;11121.721956951142;11141;11607.709807254887;11619.767604648028;11734.530618775494;11781.234219775688;11842.940785296363;11860.525578976743;11918.225315354473;7931.2068793120225;7935.880961785462;7957.482763620346;8021.2379823916635;8051.718189863327;8285.585720714062;9631.470269135265;9668.951655241679;9733.124018838338;9742.659006934724;8334.416590838584;8383.455075420079;8437.789055273422;8438.620262088098;8461.61922713467;8206.302464290602;8241.423100382915;8295.170482951899;8302.294304984143;8330.375221858507;7390.187079421152;7547.471676982778;7548.471606987678;7560.319571238615;7568.129665088585;5488;5512.118061110364;5661.603687741983;5683.431656834284;5737.139429085671;5498.120300751861;5714.485696287354;5725.484706376446;5811.389804070681;5870.853228669317 01:54:27 INFO - PROCESS | 1644 | |4;prototype.html;364.6116885516923;377.2492739448497;380.2584959329308;386.6945113360445;390.6054884566587;4995.425526064504;5023.874403139919;5116.667416617922;5248.71132087735;5316.388615309231;4878;5221.791128354871;5263.842084737455;5274.4198138204765;5325.2723093834375;4243.257429949762;4327.913441731179;4400.691951563392;4408.933865992013;4425.7565833879125;4275.700700950934;4416.5583441655735;4468.240399132155;4473.82104715811;4485.529019452943;4006.397441023599;4059.8985025374304;4298.097399546084;4435.068635586531;4461.196984542781;3032.7725420593324;3623.6738693517445;3935.3506671399186;4400.581944715263;4464.330350447426;3453.9983404812574;4011.8796436106963;4037.7173597848164;4049.777262250575;4066.207089617496;5046.949530504685;5153.484651534854;5212.739363031871;5228.843134705965;5237.921431178535;4703.905921881577;5073.746312684351;5101.668391554537;5140.997505486432;5144.254083157921;5127.410502744161;5136.5890728741615;5483.8080667176855;5583.553315734772;5665.2351932489;3925.666318362914;4079.816853112594;4530.731395209346;4530.932036019462;4571.474280457756;4940.5059494050665;4973.726445045485;5653.060408587553;5979.0134627786365;6032.155498230251;3882.835149455152;4661.417322834645;4666;4684.625229981593;4698.459677137104;4435.489918659331;4647.279671650899;4749.857504274808;4792.305115758196;4796.904061918812;4431.7340959542535;4588.967482316452;4629.675922685447;4885.267209918541;4916.778744956486;4816.290216972924;5659.264295641623;5662.801801936913;5689.317281926195;5694.544436445074;1544.4594391962723;1585.5164174926758;1595.904245745259;1611.371565089616;1615.2489092572011;983.6901376066517;1178.9742923656474;1212.5513559982862;1215.726461546145;1228.6730331242416;4715.3869996900185;5095.388553373545;5150.330457040635;5192.428832828386;5317.840464785985;5478.384655727831;5816.447437493432;5922.674252916131;5940.554458415636;5963.761449541969;4691.601213896789;4723.1150114928005;4746.149001889266;4865.416150062015;5432.891342173134;2609.556375416184;2618.528664840309;2918.117814009952;3075.1856404720875;3075.2932122671687;5116.833548374448;5944.464998150187;5967.791127310524;6143.539234557426;6143.877122457526;5270.367555893241;5495.368032676213;5498.670079795225;5787.913181302284;6157.907631385533;5548.087254191581;5694.772209111589;5818.389069147764;5942.524598032147;5962.85092872673;5324.574034077187;5502.972485137549;5555.2778138841695;5972.223610930725;6002.549808764273;5336.306280183551;5537.1483685900985;5825.242718446575;5896.086106653475;5902.881942361043;5308.115618955216;5543.6396634218645;5909.645421274738;6097.451229389376;6159.445649891352;4383.276759334802;4619.607333376701;4620.491745907881;4624.49130595641;4652.604528615106;5524.66851988882;5543.8891222176135;5759.97120014414;5853.063509838576;5950.185193516019;5032.119379108714;5498.9002199559445;5725.2843394575675;5750.763585829067;5805.632733014926;5205.843824685114;5378.809448881848;5558.555315574825;5784.971075144766;5786.42135786418;4690.827293176706;4788.6056971514245;5206.167013277857;5210.973945130402;5331.3868905076415;4965.205567109245;5568.774869528697;5729.914051289151;5766.711664416678;5768.817392434625;4663.836765713184;5029.84910452687;5286.709230992333;5567.3298010595845;5663.348714897839 01:54:27 INFO - PROCESS | 1644 | |5;yui.html;704.6617623540703;706.6572712234569;722.4004076616408;765.8085478630343;862.7411776467059;12266.754664906697;14895.18076505793;15272.618184545392;16171.029738215686;16174.514764557081;14826.777598336006;14828.703425931475;14945.476908308212;14947.252637368143;14980.625484362869;904.1275169461468;934.2152591822868;935.1863878425779;941.2799208605422;942.9815798937166;807.8004163816728;938.1697197979769;939.3471537281592;940.3323640215456;943.3585162089811;974.5614473486924;986.2553771902204;986.8174387738255;987.3631507677544;988.8121256961155;975.0590679993769;976.990230097697;979.1725991537139;981.3915372469079;981.5779214937573;726.3354031061573;728.7121586973169;728.7303697631858;730.0290613484053;730.6054730445553;14737.410503579844;14781.704365912621;14862.777058344134;14890.255487225704;14915.627109322246;9465.574049167735;11087.168462365355;11273.210875238736;11426.25729327591;11700.648980530597;10880.619178328721;10892.455377231106;11013.229073964825;11039.613613523568;11516.827247591293;3577.606463289036;3607.188382613917;3709.962900370989;3817.7136714746507;3840.9039774005596;87981.56009219977;96451.51774241154;106899.46550267198;108608.07325509365;109786.9999999992;10018.3488073275;10616.097631701237;10661.307015044153;10848.620298289521;10960.890391096147;21453.356399308046;21479.248226312004;21542.784572154076;21564.35306940794;21890.343289701334;6880.793576192722;7361.963190184015;7490.213527579525;7869.449138560246;7897;4240.406343111967;4287.7641729705165;4338.457692788402;4411.382406463098;4533.886652833639;2959.245392424918;3544.7341449391397;3572.8034958077556;3572.910677233038;3604.1530240393486;639.0765344077763;642.8457170279099;645.7191121861911;664.0238848892218;665.1120753793643;643.8036398898288;671.4963777167125;677.5799004617169;690.0959742737031;716.29087203668;9578.727695733447;10516.947415263028;10554.525046372932;10626.574937002588;10639.840402393971;598.3478008970243;647.2007071266981;680.8570200258001;697.8918267668417;708.3695510995218;4922.90154196914;5030.320906677552;5041.344625198774;5047.116754567936;5052;67813.3218667807;98610;105373;106565.46717266364;108781.36827947429;83139;84982.57508712416;88130;88298.55850720705;89846;71366.64316678487;72718.63640681763;74324.62837685777;74659.62670186722;74960.62519687366;8229.90986669872;8376.664933402595;9399.154076133182;9520.095590918852;9545.188658964065;48636.027279454924;51398;52476;53775;53776;67933.66033169802;69285.61428771296;69306;69619.65190174017;69688.6515567419;10096.34373765703;10558.947205264232;11054.889451105386;11071.778564428505;12352.382380880741;14463.132212066888;14805.333759980611;14879.776803348175;15029;15816.60458488547;11475.655730328104;14034.929825350808;14107.858921410654;14645.48740794067;14678.853211468177;11352.489137988645;13486;13509.459621615026;14091.577252682437;14743.778843317144;13424.194548327132;13424.865751342752;13897.722045559234;13952.79070813959;14348.784768228275;9671.661491847752;9933.801323973625;9938.751531211778;10153.949230253802;10171.898281017095;10161.034701703325;11142.498587563417;11285.830712539155;11325.263857849213;11338.773224535627 01:54:27 INFO - PROCESS | 1644 | __end_tp_report 01:54:27 INFO - PROCESS | 1644 | __start_cc_report 01:54:27 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,414 01:54:27 INFO - PROCESS | 1644 | __end_cc_report 01:54:27 INFO - PROCESS | 1644 | __startTimestamp1492419267618__endTimestamp 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | ------- Summary: start ------- 01:54:27 INFO - PROCESS | 1644 | Number of tests: 6 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5930.88 Median:6160.91 stddev:658.90 (10.7%) stddev-sans-first:631.31 01:54:27 INFO - PROCESS | 1644 | Values: 3441.1 3462.4 3488.1 3496.7 3502.2 6006.6 6020.9 6027.5 6109.4 6129.5 6024.1 6050.6 6065.8 6066.6 6074.4 5374.2 5389.2 5440.8 5502.1 5506.5 4638.4 5524.4 5524.7 5529.9 5571.2 5287.4 5292.2 5452.8 5494.2 5506.3 5407.2 5416.2 5435.6 5488.5 5489.4 5307.3 5419.5 5425.7 5442.1 5481.0 5813.2 6058.0 6081.1 6111.8 6122.8 6204.4 6219.1 6238.1 6259.5 6260.0 6473.9 6476.6 6483.0 6483.8 6495.0 6102.5 6149.4 6160.9 6166.3 6181.8 6243.8 6304.1 6480.5 6590.4 6612.9 6257.8 6267.5 6288.5 6297.5 6312.6 6357.8 6379.1 6412.9 6428.0 6438.9 6139.8 6220.5 6265.8 6332.0 6338.2 6208.9 6234.7 6269.2 6329.6 6350.9 4476.7 4536.5 5048.9 5161.8 5165.9 4604.7 4616.5 4620.1 4639.1 4652.8 5870.3 5962.2 6089.2 6100.7 6113.8 6453.0 6460.3 6482.4 6485.1 6499.5 5603.6 5607.0 5949.5 6004.6 6074.3 4915.7 5726.8 5793.6 5803.8 5804.0 6689.4 6735.7 6736.0 6742.6 6750.7 6139.1 6207.4 6459.6 6464.2 6474.6 6417.5 6540.9 6568.4 6584.0 6614.1 6270.2 6280.7 6283.2 6290.6 6292.2 6244.1 6330.1 6364.8 6378.3 6381.1 6134.8 6161.7 6192.6 6193.8 6203.8 5657.4 5732.8 6214.3 6248.7 6267.2 6051.2 6137.8 6205.6 6223.5 6231.6 6139.6 6144.1 6149.2 6170.3 6240.3 6208.3 6208.4 6213.4 6221.6 6265.5 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:54048.04 Median:16638.14 stddev:109102.09 (655.7%) stddev-sans-first:109334.68 01:54:27 INFO - PROCESS | 1644 | Values: 717.8 800.0 820.7 944.2 944.7 37427.6 38231.8 39541.6 39628.6 39735.0 32470.8 38932.2 39185.0 39242.8 39249.6 7876.8 9519.8 10706.0 10991.5 11073.3 10718.2 10762.8 10813.9 10819.5 10825.3 10808.1 10836.4 10851.9 10859.1 10867.3 10767.5 10789.2 10807.4 10814.4 10827.1 10531.9 10552.3 10613.3 10627.0 10627.5 33714.7 34065.5 34074.5 34095.7 34102.8 3015.4 3650.9 3714.7 3733.7 3776.9 24950.1 25143.1 25199.7 26004.0 26095.5 2809.0 2876.9 2883.3 3099.8 3110.2 338044.6 340040.0 340663.0 341543.0 341872.3 44368.0 44644.8 44801.1 44831.3 44865.6 45942.8 46357.8 46385.8 46425.1 46497.3 11397.7 13598.1 13840.7 13866.7 13912.6 11690.6 11885.8 11892.0 11896.8 11905.8 11714.4 11863.0 11877.2 11934.2 12006.1 5570.6 5599.7 5604.8 5605.5 5616.6 1059.5 1101.3 1132.7 1145.7 1164.3 24129.8 24731.9 25035.5 26448.3 26938.0 1215.4 1252.0 1261.2 1269.0 1274.9 13698.3 13698.5 13861.8 13891.9 13950.2 473515.0 476002.0 476863.0 478360.2 479149.0 44512.0 56439.0 56536.2 56710.9 57096.6 1266.0 1287.8 1429.0 1441.9 1474.7 29313.6 30104.2 30278.8 30393.0 30510.9 37356.4 46766.5 47023.3 47170.5 47217.0 403808.0 405251.0 405382.0 405599.0 405605.0 31798.0 31820.4 31899.0 31903.4 32012.8 42927.0 43092.6 43658.6 43770.7 44473.3 29616.7 29799.7 30764.8 30869.4 31005.8 29136.4 29308.4 29517.4 30130.2 30260.8 26099.3 32251.9 33971.3 36207.5 36277.8 29118.0 29157.0 29469.0 29516.0 29851.0 20596.3 20973.6 23653.8 23675.9 24103.8 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:43939.59 Median:22465.72 stddev:127212.35 (566.3%) stddev-sans-first:127527.19 01:54:27 INFO - PROCESS | 1644 | Values: 388.4 408.5 419.5 431.6 445.6 19503.6 19603.6 20024.4 20706.7 20707.1 19526.8 21534.9 21602.0 22377.9 22553.5 6225.1 6749.3 6758.6 6771.1 6789.0 6336.5 6521.6 6583.3 6592.4 6604.4 5883.9 6507.4 6584.4 6585.7 6602.5 6155.0 7532.9 7677.3 7705.3 7720.3 7225.8 7359.3 7410.1 7411.9 7446.5 21090.7 25660.0 25983.7 26084.5 26165.7 2905.1 2909.2 2945.1 3015.7 3069.8 15290.2 16164.4 16428.9 16681.0 16727.5 2033.8 2061.2 2064.7 2087.2 2099.7 153649.0 153889.0 154533.0 175580.1 182977.0 19286.5 20102.9 20255.5 20270.0 20317.7 30852.8 30919.0 30961.7 31328.7 31367.8 10122.2 10214.7 10266.4 10362.8 10532.7 8604.4 8609.6 8628.7 8651.0 8763.7 9536.1 9622.2 9651.2 9701.8 9761.3 3658.9 4192.9 4297.4 4303.4 4340.0 749.6 900.8 907.1 939.9 943.4 18081.8 20174.7 20694.2 20890.8 20918.4 810.2 844.6 895.3 932.3 964.1 9300.0 10390.7 10401.3 10912.1 10917.3 639402.0 777047.0 778726.0 784898.0 800822.0 164942.0 165119.0 168388.0 170057.0 174539.1 1177.3 1218.9 1271.8 1293.4 1296.2 17974.0 18255.6 18514.1 18542.9 18803.0 50299.5 65152.3 65529.0 66140.0 66533.3 84116.0 93697.0 94444.0 95177.5 96652.0 9788.1 11177.8 11346.6 11706.2 11830.8 12607.5 12690.0 12755.3 12816.0 13058.7 9940.3 10932.9 11093.8 11173.5 11222.7 10308.6 10317.4 10324.8 10504.1 10612.5 11255.5 11427.9 11490.0 11509.6 11782.7 10194.3 11300.8 11473.3 11515.2 11517.1 9123.4 11188.8 11236.6 11302.9 11450.0 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6521.62 Median:3559.78 stddev:4050.75 (113.8%) stddev-sans-first:4043.98 01:54:27 INFO - PROCESS | 1644 | Values: 1420.9 1438.8 1464.8 1565.9 1586.4 10720.4 11085.2 11385.1 11385.4 11398.2 10555.4 10715.3 10728.2 10751.7 10797.6 2363.5 2401.8 2419.1 2419.1 2423.6 2311.2 2377.2 2408.0 2411.5 2416.1 2312.2 2379.9 2395.6 2407.8 2421.4 2310.4 2370.2 2371.6 2376.9 2377.7 2326.5 2371.3 2372.3 2375.0 2385.7 11057.8 11323.6 11334.9 11337.4 11338.5 5685.5 5898.9 5900.9 5907.8 5913.0 3449.8 3552.0 3567.5 3569.4 3591.2 1210.2 1210.4 1248.1 1261.4 1268.4 12471.1 13773.5 14516.5 14805.3 15289.7 4536.6 4575.7 4723.9 4729.8 4749.5 10249.5 11558.4 11807.3 11848.0 11895.3 5103.5 5604.8 5698.0 5790.2 5792.7 5097.5 5102.1 5130.9 5162.7 5214.4 3788.5 3878.4 3880.4 3936.2 3940.7 983.8 1020.8 1023.2 1028.9 1029.1 917.7 918.8 936.6 944.6 988.6 6272.6 6303.8 6432.7 6489.8 6531.2 927.5 978.5 980.8 993.2 1011.6 3901.9 3992.0 4056.2 4090.8 4113.7 13091.7 13451.8 13470.1 13587.7 13726.2 13419.1 13453.4 13645.9 13679.0 13687.0 8559.2 10065.0 10244.5 10245.4 10282.0 5424.0 6192.9 6487.6 6528.1 6623.2 11040.9 11121.7 11141.0 11607.7 11619.8 11734.5 11781.2 11842.9 11860.5 11918.2 7931.2 7935.9 7957.5 8021.2 8051.7 8285.6 9631.5 9669.0 9733.1 9742.7 8334.4 8383.5 8437.8 8438.6 8461.6 8206.3 8241.4 8295.2 8302.3 8330.4 7390.2 7547.5 7548.5 7560.3 7568.1 5488.0 5512.1 5661.6 5683.4 5737.1 5498.1 5714.5 5725.5 5811.4 5870.9 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4710.34 Median:5109.17 stddev:1322.15 (25.9%) stddev-sans-first:1285.00 01:54:27 INFO - PROCESS | 1644 | Values: 364.6 377.2 380.3 386.7 390.6 4995.4 5023.9 5116.7 5248.7 5316.4 4878.0 5221.8 5263.8 5274.4 5325.3 4243.3 4327.9 4400.7 4408.9 4425.8 4275.7 4416.6 4468.2 4473.8 4485.5 4006.4 4059.9 4298.1 4435.1 4461.2 3032.8 3623.7 3935.4 4400.6 4464.3 3454.0 4011.9 4037.7 4049.8 4066.2 5046.9 5153.5 5212.7 5228.8 5237.9 4703.9 5073.7 5101.7 5141.0 5144.3 5127.4 5136.6 5483.8 5583.6 5665.2 3925.7 4079.8 4530.7 4530.9 4571.5 4940.5 4973.7 5653.1 5979.0 6032.2 3882.8 4661.4 4666.0 4684.6 4698.5 4435.5 4647.3 4749.9 4792.3 4796.9 4431.7 4589.0 4629.7 4885.3 4916.8 4816.3 5659.3 5662.8 5689.3 5694.5 1544.5 1585.5 1595.9 1611.4 1615.2 983.7 1179.0 1212.6 1215.7 1228.7 4715.4 5095.4 5150.3 5192.4 5317.8 5478.4 5816.4 5922.7 5940.6 5963.8 4691.6 4723.1 4746.1 4865.4 5432.9 2609.6 2618.5 2918.1 3075.2 3075.3 5116.8 5944.5 5967.8 6143.5 6143.9 5270.4 5495.4 5498.7 5787.9 6157.9 5548.1 5694.8 5818.4 5942.5 5962.9 5324.6 5503.0 5555.3 5972.2 6002.5 5336.3 5537.1 5825.2 5896.1 5902.9 5308.1 5543.6 5909.6 6097.5 6159.4 4383.3 4619.6 4620.5 4624.5 4652.6 5524.7 5543.9 5760.0 5853.1 5950.2 5032.1 5498.9 5725.3 5750.8 5805.6 5205.8 5378.8 5558.6 5785.0 5786.4 4690.8 4788.6 5206.2 5211.0 5331.4 4965.2 5568.8 5729.9 5766.7 5768.8 4663.8 5029.8 5286.7 5567.3 5663.3 01:54:27 INFO - PROCESS | 1644 | 01:54:27 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:19967.14 Median:26584.96 stddev:28582.82 (107.5%) stddev-sans-first:28626.41 01:54:27 INFO - PROCESS | 1644 | Values: 704.7 706.7 722.4 765.8 862.7 12266.8 14895.2 15272.6 16171.0 16174.5 14826.8 14828.7 14945.5 14947.3 14980.6 904.1 934.2 935.2 941.3 943.0 807.8 938.2 939.3 940.3 943.4 974.6 986.3 986.8 987.4 988.8 975.1 977.0 979.2 981.4 981.6 726.3 728.7 728.7 730.0 730.6 14737.4 14781.7 14862.8 14890.3 14915.6 9465.6 11087.2 11273.2 11426.3 11700.6 10880.6 10892.5 11013.2 11039.6 11516.8 3577.6 3607.2 3710.0 3817.7 3840.9 87981.6 96451.5 106899.5 108608.1 109787.0 10018.3 10616.1 10661.3 10848.6 10960.9 21453.4 21479.2 21542.8 21564.4 21890.3 6880.8 7362.0 7490.2 7869.4 7897.0 4240.4 4287.8 4338.5 4411.4 4533.9 2959.2 3544.7 3572.8 3572.9 3604.2 639.1 642.8 645.7 664.0 665.1 643.8 671.5 677.6 690.1 716.3 9578.7 10516.9 10554.5 10626.6 10639.8 598.3 647.2 680.9 697.9 708.4 4922.9 5030.3 5041.3 5047.1 5052.0 67813.3 98610.0 105373.0 106565.5 108781.4 83139.0 84982.6 88130.0 88298.6 89846.0 71366.6 72718.6 74324.6 74659.6 74960.6 8229.9 8376.7 9399.2 9520.1 9545.2 48636.0 51398.0 52476.0 53775.0 53776.0 67933.7 69285.6 69306.0 69619.7 69688.7 10096.3 10558.9 11054.9 11071.8 12352.4 14463.1 14805.3 14879.8 15029.0 15816.6 11475.7 14034.9 14107.9 14645.5 14678.9 11352.5 13486.0 13509.5 14091.6 14743.8 13424.2 13424.9 13897.7 13952.8 14348.8 9671.7 9933.8 9938.8 10153.9 10171.9 10161.0 11142.5 11285.8 11325.3 11338.8 01:54:27 INFO - PROCESS | 1644 | -------- Summary: end -------- 01:54:27 INFO - PROCESS | 1644 | 01:54:28 INFO - TEST-INFO | 1644: exit 0 01:54:28 INFO - TEST-OK | dromaeo_css | took 1117067ms 01:54:28 INFO - TEST-START | kraken 01:54:28 INFO - operating with platform_type : mac_ 01:54:28 INFO - Initialising browser for kraken test... 01:54:28 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpEWgqv0/profile http://localhost:49235/getInfo.html) 01:54:31 INFO - PROCESS | 1652 | 2017-04-17 01:54:31.221 plugin-container[1654:11191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 01:54:31 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:54:31 INFO - PROCESS | 1652 | 2017-04-17 01:54:31.223 plugin-container[1654:11191] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 01:54:31 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:54:31 INFO - PROCESS | 1652 | 2017-04-17 01:54:31.223 plugin-container[1654:11191] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:54:31 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 01:54:31 INFO - PROCESS | 1652 | colorDepth:24 01:54:31 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 01:54:31 INFO - PROCESS | 1652 | __metrics 01:54:31 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 01:54:32 INFO - TEST-INFO | 1652: exit 0 01:54:32 INFO - Browser initialized. 01:54:32 INFO - Running cycle 1/1 for kraken test... 01:54:32 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/tmpEWgqv0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:54:34 INFO - PROCESS | 1655 | 2017-04-17 01:54:34.663 plugin-container[1656:11314] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 01:54:34 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:54:34 INFO - PROCESS | 1655 | 2017-04-17 01:54:34.663 plugin-container[1656:11314] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.CFPasteboardClient' 01:54:34 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:54:34 INFO - PROCESS | 1655 | 2017-04-17 01:54:34.663 plugin-container[1656:11314] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:54:44 INFO - PROCESS | 1655 | RSS: Main: 220946432 01:54:44 INFO - PROCESS | 1655 | 01:54:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 01:54:48 INFO - PROCESS | 1655 | RSS: Main: 235671552 01:54:48 INFO - PROCESS | 1655 | 01:54:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 01:54:51 INFO - PROCESS | 1655 | RSS: Main: 243503104 01:54:51 INFO - PROCESS | 1655 | 01:54:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 01:54:55 INFO - PROCESS | 1655 | RSS: Main: 247242752 01:54:55 INFO - PROCESS | 1655 | 01:54:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 01:54:57 INFO - PROCESS | 1655 | RSS: Main: 247681024 01:54:57 INFO - PROCESS | 1655 | 01:54:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:55:00 INFO - PROCESS | 1655 | RSS: Main: 249061376 01:55:00 INFO - PROCESS | 1655 | 01:55:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 01:55:04 INFO - PROCESS | 1655 | RSS: Main: 253145088 01:55:04 INFO - PROCESS | 1655 | 01:55:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 01:55:07 INFO - PROCESS | 1655 | RSS: Main: 252706816 01:55:07 INFO - PROCESS | 1655 | 01:55:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 01:55:11 INFO - PROCESS | 1655 | RSS: Main: 252788736 01:55:11 INFO - PROCESS | 1655 | 01:55:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:55:13 INFO - PROCESS | 1655 | RSS: Main: 252170240 01:55:13 INFO - PROCESS | 1655 | 01:55:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:55:16 INFO - PROCESS | 1655 | RSS: Main: 252313600 01:55:16 INFO - PROCESS | 1655 | 01:55:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:55:19 INFO - PROCESS | 1655 | RSS: Main: 252334080 01:55:19 INFO - PROCESS | 1655 | 01:55:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:55:22 INFO - PROCESS | 1655 | RSS: Main: 252641280 01:55:22 INFO - PROCESS | 1655 | 01:55:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:55:26 INFO - PROCESS | 1655 | RSS: Main: 252706816 01:55:26 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 01:55:28 INFO - PROCESS | 1655 | RSS: Main: 254717952 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | __start_tp_report 01:55:28 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 01:55:28 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 01:55:28 INFO - PROCESS | 1655 | |i|pagename|runs| 01:55:28 INFO - PROCESS | 1655 | |0;ai-astar;107;120;119;120;120;120;125;119;120;120 01:55:28 INFO - PROCESS | 1655 | |1;audio-beat-detection;128;108;106;145;133;142;111;112;128;112 01:55:28 INFO - PROCESS | 1655 | |2;audio-dft;203;196;191;215;197;196;197;196;200;200 01:55:28 INFO - PROCESS | 1655 | |3;audio-fft;88;61;60;90;61;64;61;60;76;61 01:55:28 INFO - PROCESS | 1655 | |4;audio-oscillator;86;70;85;74;71;71;82;70;69;82 01:55:28 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;104;88;90;90;90;90;106;107;91;91 01:55:28 INFO - PROCESS | 1655 | |6;imaging-darkroom;99;99;102;105;99;99;97;153;98;99 01:55:28 INFO - PROCESS | 1655 | |7;imaging-desaturate;92;94;92;91;91;90;90;90;90;109 01:55:28 INFO - PROCESS | 1655 | |8;json-parse-financial;65;65;66;68;79;99;105;83;69;70 01:55:28 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;56;60;60;71;62;63;57;57;58;61 01:55:28 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;88;87;99;100;86;85;84;102;86 01:55:28 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;139;140;140;136;143;162;154;140;182;159 01:55:28 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;224;170;167;205;220;186;181;166;152;222 01:55:28 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;66;67;66;68;64;67;63;66;64 01:55:28 INFO - PROCESS | 1655 | __end_tp_report 01:55:28 INFO - PROCESS | 1655 | __start_cc_report 01:55:28 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1023 01:55:28 INFO - PROCESS | 1655 | __end_cc_report 01:55:28 INFO - PROCESS | 1655 | __startTimestamp1492419328503__endTimestamp 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | ------- Summary: start ------- 01:55:28 INFO - PROCESS | 1655 | Number of tests: 14 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:119.00 Median:120.00 stddev:4.55 (3.8%) stddev-sans-first:1.80 01:55:28 INFO - PROCESS | 1655 | Values: 107.0 120.0 119.0 120.0 120.0 120.0 125.0 119.0 120.0 120.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:128.00 stddev:14.50 (11.3%) stddev-sans-first:15.24 01:55:28 INFO - PROCESS | 1655 | Values: 128.0 108.0 106.0 145.0 133.0 142.0 111.0 112.0 128.0 112.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:199.10 Median:198.50 stddev:6.44 (3.2%) stddev-sans-first:6.67 01:55:28 INFO - PROCESS | 1655 | Values: 203.0 196.0 191.0 215.0 197.0 196.0 197.0 196.0 200.0 200.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:68.20 Median:62.50 stddev:11.96 (19.1%) stddev-sans-first:10.32 01:55:28 INFO - PROCESS | 1655 | Values: 88.0 61.0 60.0 90.0 61.0 64.0 61.0 60.0 76.0 61.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:78.00 stddev:6.90 (8.8%) stddev-sans-first:6.29 01:55:28 INFO - PROCESS | 1655 | Values: 86.0 70.0 85.0 74.0 71.0 71.0 82.0 70.0 69.0 82.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:90.00 stddev:7.65 (8.5%) stddev-sans-first:7.33 01:55:28 INFO - PROCESS | 1655 | Values: 104.0 88.0 90.0 90.0 90.0 90.0 106.0 107.0 91.0 91.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:99.00 stddev:17.02 (17.2%) stddev-sans-first:17.91 01:55:28 INFO - PROCESS | 1655 | Values: 99.0 99.0 102.0 105.0 99.0 99.0 97.0 153.0 98.0 99.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:91.00 stddev:5.80 (6.4%) stddev-sans-first:6.14 01:55:28 INFO - PROCESS | 1655 | Values: 92.0 94.0 92.0 91.0 91.0 90.0 90.0 90.0 90.0 109.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:76.90 Median:69.50 stddev:14.57 (21.0%) stddev-sans-first:14.81 01:55:28 INFO - PROCESS | 1655 | Values: 65.0 65.0 66.0 68.0 79.0 99.0 105.0 83.0 69.0 70.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:4.35 (7.2%) stddev-sans-first:4.30 01:55:28 INFO - PROCESS | 1655 | Values: 56.0 60.0 60.0 71.0 62.0 63.0 57.0 57.0 58.0 61.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:86.00 stddev:7.04 (8.2%) stddev-sans-first:7.29 01:55:28 INFO - PROCESS | 1655 | Values: 86.0 88.0 87.0 99.0 100.0 86.0 85.0 84.0 102.0 86.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:148.50 stddev:14.64 (9.9%) stddev-sans-first:15.02 01:55:28 INFO - PROCESS | 1655 | Values: 139.0 140.0 140.0 136.0 143.0 162.0 154.0 140.0 182.0 159.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.30 Median:195.50 stddev:26.55 (13.6%) stddev-sans-first:25.02 01:55:28 INFO - PROCESS | 1655 | Values: 224.0 170.0 167.0 205.0 220.0 186.0 181.0 166.0 152.0 222.0 01:55:28 INFO - PROCESS | 1655 | 01:55:28 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:1.62 (2.4%) stddev-sans-first:1.66 01:55:28 INFO - PROCESS | 1655 | Values: 67.0 66.0 67.0 66.0 68.0 64.0 67.0 63.0 66.0 64.0 01:55:28 INFO - PROCESS | 1655 | -------- Summary: end -------- 01:55:28 INFO - PROCESS | 1655 | 01:55:29 INFO - TEST-INFO | 1655: exit 0 01:55:29 INFO - TEST-OK | kraken | took 61099ms 01:55:29 INFO - SUITE-END | took 1178s 01:55:29 INFO - Completed test suite (00:19:38) 01:55:29 INFO - javascript benchmark 01:55:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7652.895461913335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.0709108540696, 3462.4113900636894, 3488.127968007998, 3496.667816557427, 3502.247016891368, 6006.579539432241, 6020.939790602092, 6027.5479339049525, 6109.389061093889, 6129.509639228862, 6024.126501657257, 6050.576459647827, 6065.848353791157, 6066.605670631406, 6074.392560743923, 5374.247605335257, 5389.191621256823, 5440.782368705267, 5502.064649009658, 5506.476884695968, 4638.4433867935895, 5524.392316845144, 5524.723763811813, 5529.944700553004, 5571.247881535981, 5287.365516138049, 5292.206169074631, 5452.836414907559, 5494.203340515623, 5506.256655351536, 5407.242985981966, 5416.160495123262, 5435.646682965595, 5488.4785945335125, 5489.368722596912, 5307.336582927134, 5419.512243898029, 5425.674459532401, 5442.074847275972, 5481.0, 5813.157092221646, 6057.969710151465, 6081.118237855486, 6111.847203819895, 6122.755089796403, 6204.441600255954, 6219.09823075656, 6238.095476155977, 6259.499240060784, 6260.0, 6473.93526064738, 6476.6114033158165, 6483.0, 6483.7730679425995, 6494.993276042221, 6102.481289090389, 6149.446549810449, 6160.907586386208, 6166.260048794128, 6181.752729890754, 6243.7814676486105, 6304.117423560613, 6480.546361754727, 6590.373914478117, 6612.90080648781, 6257.78097766576, 6267.4986001119805, 6288.496920246369, 6297.464715499143, 6312.589681670676, 6357.809265722036, 6379.138816259839, 6412.935870641327, 6427.971524556048, 6438.871222575616, 6139.798803208702, 6220.533459990428, 6265.780697675651, 6331.96834015827, 6338.239411270585, 6208.875822483525, 6234.657093859882, 6269.153664255358, 6329.588576742497, 6350.904736428957, 4476.664250181249, 4536.500984891659, 5048.939722658283, 5161.845144645667, 5165.889553401199, 4604.677672562889, 4616.538346165356, 4620.0990806793025, 4639.095376401569, 4652.813887444464, 5870.295564532284, 5962.22491076166, 6089.208402907594, 6100.664463454553, 6113.84715382119, 6453.032045193258, 6460.257070436866, 6482.384173503509, 6485.092087107809, 6499.512536559777, 5603.635763675348, 5607.018771714934, 5949.524038076944, 6004.579679422486, 6074.331823499411, 4915.729634870045, 5726.770929162787, 5793.623414478046, 5803.796867109715, 5804.0, 6689.431398331197, 6735.663216839041, 6735.966320168466, 6742.6291553965, 6750.72997080131, 6139.140520327069, 6207.410296021871, 6459.580127291711, 6464.191976003, 6474.5791523550815, 6417.518686098468, 6540.934590654033, 6568.441682457044, 6583.967080164569, 6614.140161778938, 6270.184875966095, 6280.685965701605, 6283.183186185766, 6290.622562646256, 6292.244930608356, 6244.125822384961, 6330.113784070142, 6364.80905572815, 6378.266499352449, 6381.106645069601, 6134.773364742599, 6161.722722477589, 6192.59748116371, 6193.783217587363, 6203.844903877259, 5657.4059723728415, 5732.770689172553, 6214.347493513116, 6248.687565621792, 6267.185265915402, 6051.152838602688, 6137.84655383619, 6205.627662340094, 6223.502119830312, 6231.563790534619, 6139.614800607318, 6144.064619644976, 6149.2313460817395, 6170.259568851767, 6240.344763799736, 6208.348123447154, 6208.441240288215, 6213.37866213375, 6221.564490485623, 6265.467435268053], "value": 5888.986854188362, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.7882524655226, 799.9720009799659, 820.7209548753425, 944.2115833279212, 944.7118628818216, 37427.625723742756, 38231.80884095579, 39541.604583954155, 39628.60371396289, 39735.0, 32470.83764581174, 38932.22135557294, 39185.0, 39242.8037859811, 39249.607503924955, 7876.763697089083, 9519.762005949837, 10706.036456718894, 10991.450427478634, 11073.280236784583, 10718.196135289845, 10762.784744305109, 10813.945930270338, 10819.459027048617, 10825.296355736851, 10808.135349172047, 10836.403997780119, 10851.945740271327, 10859.13126949846, 10867.347959122439, 10767.461626918663, 10789.190810689151, 10807.351558906452, 10814.35113893161, 10827.133829293676, 10531.894681053245, 10552.261341706084, 10613.257072004963, 10626.990435908576, 10627.52176152075, 33714.662853371396, 34065.48901766475, 34074.48888266678, 34095.6590434095, 34102.806401775815, 3015.4270688569322, 3650.9229777215787, 3714.665680088778, 3733.6826369758605, 3776.924461510782, 24950.12674556382, 25143.119990800235, 25199.748002520108, 26004.0, 26095.478090438086, 2808.985955070232, 2876.949913281658, 2883.2503549077182, 3099.767517436201, 3110.1913502489288, 338044.61955380376, 340040.0, 340663.0, 341543.0, 341872.2906385477, 44368.0, 44644.77677611591, 44801.10397792091, 44831.327530087074, 44865.55134448679, 45942.770286148356, 46357.76821115873, 46385.76807115943, 46425.071498569836, 46497.30254046192, 11397.658070257905, 13598.11612245201, 13840.723185536233, 13866.65333366674, 13912.582622521139, 11690.649280521598, 11885.821712674317, 11892.0, 11896.762064758655, 11905.761884762256, 11714.414279286002, 11863.0, 11877.168598198043, 11934.22427542207, 12006.099542534344, 5570.582206334541, 5599.664020158803, 5604.8318550443555, 5605.523530499871, 5616.6068375213345, 1059.5126241928645, 1101.3336931156696, 1132.6715252576826, 1145.7192987718063, 1164.2768702320443, 24129.793510324413, 24731.876340618182, 25035.499290014097, 26448.338791530365, 26938.0, 1215.4408971873038, 1251.9889665354835, 1261.1613277170761, 1268.9936550317188, 1274.936253187337, 13698.315084245747, 13698.452061917611, 13861.792073118912, 13891.86108138926, 13950.162990220417, 473515.0, 476002.0, 476863.0, 478360.21639783855, 479149.0, 44512.0, 56439.0, 56536.15195772066, 56710.865782684115, 57096.57258568569, 1265.9936700316623, 1287.8003909394058, 1428.978565321521, 1441.942322307096, 1474.741920163967, 29313.56029659514, 30104.24739381533, 30278.84860575683, 30393.0, 30510.932117375785, 37356.439653404675, 46766.53233467758, 47023.294650579584, 47170.52829471661, 47217.0, 403808.0, 405251.0, 405382.0, 405599.0, 405605.0, 31798.0, 31820.363592728478, 31899.0, 31903.36193276168, 32012.839935801105, 42927.0, 43092.56907431011, 43658.56341436545, 43770.68687939367, 44473.33290000588, 29616.703832961393, 29799.70200298056, 30764.846175768977, 30869.382612348076, 31005.844970775, 29136.417271654023, 29308.413831723672, 29517.409651807273, 30130.246743830703, 30260.84869575638, 26099.347516312246, 32251.87118450843, 33971.30139509936, 36207.456888146175, 36277.818610906776, 29118.0, 29157.0, 29469.0, 29515.966941156956, 29851.0, 20596.27913023097, 20973.596539207643, 23653.763462365157, 23675.881620592474, 24103.758962410153], "value": 18340.589792750998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.42125233402237, 408.4710300161291, 419.4526143382886, 431.63311185492336, 445.55889669227474, 19503.60992780145, 19603.607927841436, 20024.399268021945, 20706.689399658997, 20707.068181931812, 19526.804731952678, 21534.89232553839, 21602.0, 22377.888110559423, 22553.54892902141, 6225.128482012522, 6749.325067493236, 6758.594484330932, 6771.085903403052, 6789.049533065375, 6336.46140078094, 6521.608703477783, 6583.3087525809815, 6592.439642630382, 6604.405603495685, 5883.882062408129, 6507.44686701631, 6584.407403333699, 6585.703643336062, 6602.504812139085, 6155.01519756839, 7532.887006694904, 7677.347425468842, 7705.306522412954, 7720.30517253447, 7225.783226503161, 7359.264073592652, 7410.073740782403, 7411.85176296471, 7446.515976461513, 21090.683639745417, 25660.0, 25983.74016259851, 26084.478310433686, 26165.73834261671, 2905.1139402482236, 2909.156344660046, 2945.1311863000465, 3015.6833532478995, 3069.754419646423, 15290.235488225544, 16164.434244801376, 16428.917855410647, 16681.0, 16727.498175054767, 2033.7559492860803, 2061.2376418162553, 2064.721262629556, 2087.2068613926613, 2099.737532808399, 153649.0, 153889.0, 154533.0, 175580.12209938996, 182977.0, 19286.517837053907, 20102.894340811396, 20255.4936126598, 20269.9865006752, 20317.695234571493, 30852.845735771178, 30919.0, 30961.69038309588, 31328.686713133033, 31367.84316078405, 10122.240831937634, 10214.744631384276, 10266.435346055889, 10362.792744145076, 10532.684019479428, 8604.397692161614, 8609.61256743448, 8628.654853805903, 8651.0, 8763.737087887373, 9536.141747242782, 9622.230221582397, 9651.227901767841, 9701.805963880683, 9761.316707830383, 3658.920618417573, 4192.853250136231, 4297.39836422901, 4303.4190384298345, 4340.0, 749.576489283564, 900.783811885156, 907.1019690506351, 939.9342046056711, 943.4480828715141, 18081.819181808012, 20174.697379539317, 20694.17223311051, 20890.791092088886, 20918.372448826558, 810.1776696652902, 844.6494704697604, 895.2882458445421, 932.2961164320894, 964.0889359555293, 9299.95350023259, 10390.688279351632, 10401.323913945569, 10912.127029837595, 10917.344959302467, 639402.0, 777047.0, 778726.0, 784898.0, 800822.0, 164942.0, 165119.0, 168388.0, 170057.0, 174539.12730436266, 1177.3348058347178, 1218.865924748277, 1271.771081205392, 1293.3662505372488, 1296.2028352563107, 17974.01142937151, 18255.634887302444, 18514.074296285402, 18542.907285463487, 18803.0, 50299.49700503094, 65152.34847651463, 65529.017064743115, 66140.0, 66533.3346666527, 84116.0, 93697.0, 94444.0, 95177.52411237899, 96652.0, 9788.119069283799, 11177.776444471228, 11346.602868899548, 11706.239094459173, 11830.763384732429, 12607.49570017189, 12690.0, 12755.298458584866, 12816.038797090441, 13058.673533161747, 9940.30417870771, 10932.890671093506, 11093.778124437627, 11173.497192626513, 11222.719432014264, 10308.639197628047, 10317.432541210306, 10324.793504129726, 10504.107150891954, 10612.469376531297, 11255.549778008788, 11427.885721143015, 11490.0, 11509.597164099216, 11782.705432364259, 10194.337368071052, 11300.830487542858, 11473.340153197369, 11515.19393642437, 11517.136214783757, 9123.366909344662, 11188.776224475629, 11236.606718765132, 11302.869713028631, 11450.026747726537], "value": 11516.737959763539, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.9218492982884, 1438.8345440193443, 1464.8095747552816, 1565.9138747368897, 1586.4050980882168, 10720.35677859329, 11085.22403431758, 11385.089192864572, 11385.373804440753, 11398.202125851194, 10555.366677999325, 10715.303505272172, 10728.195385346111, 10751.731206719836, 10797.622083227088, 2363.4918492524102, 2401.819863510235, 2419.0686585664557, 2419.1049311754678, 2423.5758742220128, 2311.2257393773107, 2377.1679912030736, 2407.9759202407927, 2411.4815314707334, 2416.1060407649193, 2312.167619656922, 2379.9048038078454, 2395.6406539019113, 2407.831451798375, 2421.4309637235324, 2310.353101131686, 2370.229675355508, 2371.5968285391436, 2376.916807911715, 2377.6671266022686, 2326.4649130699863, 2371.312319427381, 2372.323887692017, 2375.002498950445, 2385.7375688674233, 11057.778844423146, 11323.603673871457, 11334.943325283402, 11337.37644429556, 11338.489767960462, 5685.545156387479, 5898.852528686774, 5900.881982360329, 5907.763689452417, 5913.0, 3449.810260435675, 3552.0409489437707, 3567.518385018015, 3569.39320315547, 3591.174029973121, 1210.152892974921, 1210.3645586067298, 1248.1200753468784, 1261.356708078877, 1268.3975111821903, 12471.127021108527, 13773.517926872611, 14516.491922782652, 14805.259737013106, 15289.694206115815, 4536.56902594253, 4575.702579332333, 4723.881902952454, 4729.763511824395, 4749.525047495292, 10249.487525623688, 11558.364289964133, 11807.291562506278, 11847.992920601846, 11895.345755983504, 5103.5406813386235, 5604.775808967595, 5698.0, 5790.189373487715, 5792.7103644818435, 5097.46476619957, 5102.10713125209, 5130.9230361544605, 5162.690238585622, 5214.374275086938, 3788.5453745550712, 3878.4182372643763, 3880.428426487277, 3936.2324346752107, 3940.6847452203756, 983.7638966647953, 1020.7550187954837, 1023.1865666794915, 1028.9022542858415, 1029.145808978546, 917.6742256498846, 918.8055527813815, 936.6066252174103, 944.5654998700537, 988.5502096546054, 6272.560920735596, 6303.779367722108, 6432.71052802625, 6489.805305840832, 6531.216254049545, 927.4945154890601, 978.5205249427871, 980.8185485685171, 993.2203220471895, 1011.6105299459614, 3901.8634347798256, 3992.0, 4056.1887622475624, 4090.8159132839096, 4113.745307681186, 13091.672708182372, 13451.798223026466, 13470.123968589363, 13587.660308492366, 13726.245056521788, 13419.127756695783, 13453.394597243148, 13645.863541364459, 13679.0, 13687.0, 8559.22966932979, 10065.043820837009, 10244.48777561119, 10245.385276883262, 10281.971802819658, 5424.0, 6192.9337038508775, 6487.578307410004, 6528.118703974903, 6623.205215374186, 11040.889591103985, 11121.721956951142, 11141.0, 11607.709807254887, 11619.767604648028, 11734.530618775494, 11781.234219775688, 11842.940785296363, 11860.525578976743, 11918.225315354473, 7931.2068793120225, 7935.880961785462, 7957.482763620346, 8021.2379823916635, 8051.718189863327, 8285.585720714062, 9631.470269135265, 9668.951655241679, 9733.124018838338, 9742.659006934724, 8334.416590838584, 8383.455075420079, 8437.789055273422, 8438.620262088098, 8461.61922713467, 8206.302464290602, 8241.423100382915, 8295.170482951899, 8302.294304984143, 8330.375221858507, 7390.187079421152, 7547.471676982778, 7548.471606987678, 7560.319571238615, 7568.129665088585, 5488.0, 5512.118061110364, 5661.603687741983, 5683.431656834284, 5737.139429085671, 5498.120300751861, 5714.485696287354, 5725.484706376446, 5811.389804070681, 5870.853228669317], "value": 5009.873028847422, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.6116885516923, 377.2492739448497, 380.2584959329308, 386.6945113360445, 390.6054884566587, 4995.425526064504, 5023.874403139919, 5116.667416617922, 5248.71132087735, 5316.388615309231, 4878.0, 5221.791128354871, 5263.842084737455, 5274.4198138204765, 5325.2723093834375, 4243.257429949762, 4327.913441731179, 4400.691951563392, 4408.933865992013, 4425.7565833879125, 4275.700700950934, 4416.5583441655735, 4468.240399132155, 4473.82104715811, 4485.529019452943, 4006.397441023599, 4059.8985025374304, 4298.097399546084, 4435.068635586531, 4461.196984542781, 3032.7725420593324, 3623.6738693517445, 3935.3506671399186, 4400.581944715263, 4464.330350447426, 3453.9983404812574, 4011.8796436106963, 4037.7173597848164, 4049.777262250575, 4066.207089617496, 5046.949530504685, 5153.484651534854, 5212.739363031871, 5228.843134705965, 5237.921431178535, 4703.905921881577, 5073.746312684351, 5101.668391554537, 5140.997505486432, 5144.254083157921, 5127.410502744161, 5136.5890728741615, 5483.8080667176855, 5583.553315734772, 5665.2351932489, 3925.666318362914, 4079.816853112594, 4530.731395209346, 4530.932036019462, 4571.474280457756, 4940.5059494050665, 4973.726445045485, 5653.060408587553, 5979.0134627786365, 6032.155498230251, 3882.835149455152, 4661.417322834645, 4666.0, 4684.625229981593, 4698.459677137104, 4435.489918659331, 4647.279671650899, 4749.857504274808, 4792.305115758196, 4796.904061918812, 4431.7340959542535, 4588.967482316452, 4629.675922685447, 4885.267209918541, 4916.778744956486, 4816.290216972924, 5659.264295641623, 5662.801801936913, 5689.317281926195, 5694.544436445074, 1544.4594391962723, 1585.5164174926758, 1595.904245745259, 1611.371565089616, 1615.2489092572011, 983.6901376066517, 1178.9742923656474, 1212.5513559982862, 1215.726461546145, 1228.6730331242416, 4715.3869996900185, 5095.388553373545, 5150.330457040635, 5192.428832828386, 5317.840464785985, 5478.384655727831, 5816.447437493432, 5922.674252916131, 5940.554458415636, 5963.761449541969, 4691.601213896789, 4723.1150114928005, 4746.149001889266, 4865.416150062015, 5432.891342173134, 2609.556375416184, 2618.528664840309, 2918.117814009952, 3075.1856404720875, 3075.2932122671687, 5116.833548374448, 5944.464998150187, 5967.791127310524, 6143.539234557426, 6143.877122457526, 5270.367555893241, 5495.368032676213, 5498.670079795225, 5787.913181302284, 6157.907631385533, 5548.087254191581, 5694.772209111589, 5818.389069147764, 5942.524598032147, 5962.85092872673, 5324.574034077187, 5502.972485137549, 5555.2778138841695, 5972.223610930725, 6002.549808764273, 5336.306280183551, 5537.1483685900985, 5825.242718446575, 5896.086106653475, 5902.881942361043, 5308.115618955216, 5543.6396634218645, 5909.645421274738, 6097.451229389376, 6159.445649891352, 4383.276759334802, 4619.607333376701, 4620.491745907881, 4624.49130595641, 4652.604528615106, 5524.66851988882, 5543.8891222176135, 5759.97120014414, 5853.063509838576, 5950.185193516019, 5032.119379108714, 5498.9002199559445, 5725.2843394575675, 5750.763585829067, 5805.632733014926, 5205.843824685114, 5378.809448881848, 5558.555315574825, 5784.971075144766, 5786.42135786418, 4690.827293176706, 4788.6056971514245, 5206.167013277857, 5210.973945130402, 5331.3868905076415, 4965.205567109245, 5568.774869528697, 5729.914051289151, 5766.711664416678, 5768.817392434625, 4663.836765713184, 5029.84910452687, 5286.709230992333, 5567.3298010595845, 5663.348714897839], "value": 4322.1214032869975, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.6617623540703, 706.6572712234569, 722.4004076616408, 765.8085478630343, 862.7411776467059, 12266.754664906697, 14895.18076505793, 15272.618184545392, 16171.029738215686, 16174.514764557081, 14826.777598336006, 14828.703425931475, 14945.476908308212, 14947.252637368143, 14980.625484362869, 904.1275169461468, 934.2152591822868, 935.1863878425779, 941.2799208605422, 942.9815798937166, 807.8004163816728, 938.1697197979769, 939.3471537281592, 940.3323640215456, 943.3585162089811, 974.5614473486924, 986.2553771902204, 986.8174387738255, 987.3631507677544, 988.8121256961155, 975.0590679993769, 976.990230097697, 979.1725991537139, 981.3915372469079, 981.5779214937573, 726.3354031061573, 728.7121586973169, 728.7303697631858, 730.0290613484053, 730.6054730445553, 14737.410503579844, 14781.704365912621, 14862.777058344134, 14890.255487225704, 14915.627109322246, 9465.574049167735, 11087.168462365355, 11273.210875238736, 11426.25729327591, 11700.648980530597, 10880.619178328721, 10892.455377231106, 11013.229073964825, 11039.613613523568, 11516.827247591293, 3577.606463289036, 3607.188382613917, 3709.962900370989, 3817.7136714746507, 3840.9039774005596, 87981.56009219977, 96451.51774241154, 106899.46550267198, 108608.07325509365, 109786.9999999992, 10018.3488073275, 10616.097631701237, 10661.307015044153, 10848.620298289521, 10960.890391096147, 21453.356399308046, 21479.248226312004, 21542.784572154076, 21564.35306940794, 21890.343289701334, 6880.793576192722, 7361.963190184015, 7490.213527579525, 7869.449138560246, 7897.0, 4240.406343111967, 4287.7641729705165, 4338.457692788402, 4411.382406463098, 4533.886652833639, 2959.245392424918, 3544.7341449391397, 3572.8034958077556, 3572.910677233038, 3604.1530240393486, 639.0765344077763, 642.8457170279099, 645.7191121861911, 664.0238848892218, 665.1120753793643, 643.8036398898288, 671.4963777167125, 677.5799004617169, 690.0959742737031, 716.29087203668, 9578.727695733447, 10516.947415263028, 10554.525046372932, 10626.574937002588, 10639.840402393971, 598.3478008970243, 647.2007071266981, 680.8570200258001, 697.8918267668417, 708.3695510995218, 4922.90154196914, 5030.320906677552, 5041.344625198774, 5047.116754567936, 5052.0, 67813.3218667807, 98610.0, 105373.0, 106565.46717266364, 108781.36827947429, 83139.0, 84982.57508712416, 88130.0, 88298.55850720705, 89846.0, 71366.64316678487, 72718.63640681763, 74324.62837685777, 74659.62670186722, 74960.62519687366, 8229.90986669872, 8376.664933402595, 9399.154076133182, 9520.095590918852, 9545.188658964065, 48636.027279454924, 51398.0, 52476.0, 53775.0, 53776.0, 67933.66033169802, 69285.61428771296, 69306.0, 69619.65190174017, 69688.6515567419, 10096.34373765703, 10558.947205264232, 11054.889451105386, 11071.778564428505, 12352.382380880741, 14463.132212066888, 14805.333759980611, 14879.776803348175, 15029.0, 15816.60458488547, 11475.655730328104, 14034.929825350808, 14107.858921410654, 14645.48740794067, 14678.853211468177, 11352.489137988645, 13486.0, 13509.459621615026, 14091.577252682437, 14743.778843317144, 13424.194548327132, 13424.865751342752, 13897.722045559234, 13952.79070813959, 14348.784768228275, 9671.661491847752, 9933.801323973625, 9938.751531211778, 10153.949230253802, 10171.898281017095, 10161.034701703325, 11142.498587563417, 11285.830712539155, 11325.263857849213, 11338.773224535627], "value": 7457.746245318177, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 119.0, 120.0, 120.0, 120.0, 125.0, 119.0, 120.0, 120.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 108.0, 106.0, 145.0, 133.0, 142.0, 111.0, 112.0, 128.0, 112.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 196.0, 191.0, 215.0, 197.0, 196.0, 197.0, 196.0, 200.0, 200.0], "value": 199.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 61.0, 60.0, 90.0, 61.0, 64.0, 61.0, 60.0, 76.0, 61.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 85.0, 74.0, 71.0, 71.0, 82.0, 70.0, 69.0, 82.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 88.0, 90.0, 90.0, 90.0, 90.0, 106.0, 107.0, 91.0, 91.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 102.0, 105.0, 99.0, 99.0, 97.0, 153.0, 98.0, 99.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 91.0, 91.0, 90.0, 90.0, 90.0, 90.0, 109.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 68.0, 79.0, 99.0, 105.0, 83.0, 69.0, 70.0], "value": 76.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 60.0, 71.0, 62.0, 63.0, 57.0, 57.0, 58.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 87.0, 99.0, 100.0, 86.0, 85.0, 84.0, 102.0, 86.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 140.0, 136.0, 143.0, 162.0, 154.0, 140.0, 182.0, 159.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 170.0, 167.0, 205.0, 220.0, 186.0, 181.0, 166.0, 152.0, 222.0], "value": 189.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 68.0, 64.0, 67.0, 63.0, 66.0, 64.0], "value": 65.8, "unit": "score"}]}]} 01:55:29 INFO - Return code: 0 01:55:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:55:29 ERROR - # TBPL SUCCESS # 01:55:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:55:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:55:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:55:29 INFO - 2017-04-17 01:55:29.854 screenresolution[1660:11698] starting screenresolution argv=screenresolution get 01:55:29 INFO - 2017-04-17 01:55:29.857 screenresolution[1660:11698] Display 0: 1600x1200x32@60 01:55:29 INFO - 2017-04-17 01:55:29.862 screenresolution[1661:11701] starting screenresolution argv=screenresolution list 01:55:29 INFO - Available Modes on Display 0 01:55:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:55:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:55:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:55:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:55:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:55:30 INFO - Intel Iris: 01:55:30 INFO - Chipset Model: Intel Iris 01:55:30 INFO - Type: GPU 01:55:30 INFO - Bus: Built-In 01:55:30 INFO - VRAM (Dynamic, Max): 1536 MB 01:55:30 INFO - Vendor: Intel (0x8086) 01:55:30 INFO - Device ID: 0x0a2e 01:55:30 INFO - Revision ID: 0x0009 01:55:30 INFO - Displays: 01:55:30 INFO - Display: 01:55:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:55:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:55:30 INFO - Display Serial Number: 1600x1200 60 01:55:30 INFO - Main Display: Yes 01:55:30 INFO - Mirror: Off 01:55:30 INFO - Online: Yes 01:55:30 INFO - Rotation: Supported 01:55:30 INFO - Return code: 0 01:55:30 INFO - Running post-action listener: _resource_record_post_action 01:55:30 INFO - [mozharness: 2017-04-17 08:55:30.096401Z] Finished run-tests step (success) 01:55:30 INFO - Running post-run listener: _resource_record_post_run 01:55:30 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 34140160; Write bytes: 514664960; Read time: 208; Write time: 1616 01:55:30 INFO - TinderboxPrint: CPU usage
54.6% 01:55:30 INFO - TinderboxPrint: I/O read bytes / time
34,140,160 / 208 01:55:30 INFO - TinderboxPrint: I/O write bytes / time
514,664,960 / 1,616 01:55:30 INFO - TinderboxPrint: CPU idle
2,178.4 (45.4%) 01:55:30 INFO - TinderboxPrint: CPU system
961.8 (20.0%) 01:55:30 INFO - TinderboxPrint: CPU user
1,657.5 (34.5%) 01:55:30 INFO - TinderboxPrint: Swap in / out
238,964,736 / 0 01:55:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200700416; Write bytes: 201545216; Read time: 15509; Write time: 429 01:55:30 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 33210368; Write bytes: 303199232; Read time: 196; Write time: 1153 01:55:30 INFO - Running post-run listener: _upload_blobber_files 01:55:30 INFO - Blob upload gear active. 01:55:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:55:30 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 01:55:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:55:31 INFO - (blobuploader) - INFO - Open directory for files ... 01:55:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:55:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:55:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:32 INFO - (blobuploader) - INFO - Done attempting. 01:55:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:55:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:55:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:33 INFO - (blobuploader) - INFO - Done attempting. 01:55:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:55:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:55:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:34 INFO - (blobuploader) - INFO - Done attempting. 01:55:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:55:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:55:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:37 INFO - (blobuploader) - INFO - Done attempting. 01:55:37 INFO - (blobuploader) - INFO - Iteration through files over. 01:55:37 INFO - Return code: 0 01:55:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:55:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:55:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f55f371da4bfc27757f2c16e04ed45e412f9bfdf58d9555b06fad62d52118a664b27b80d9e871ded0f3a0fb561bbe5a4b25a19da386aa61edc1af3a8401522c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79f14a23e89fbb311b95756073d4bd1041b9f8b466ff524df9f9d99e56c1c788f905148feaeb6d96879562ef3c6cfbbf394c3448e738f456953269e79c45a353", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9f97f61deddaf01cade7a9e724072a4177179531a2fbeae63bfd6da3afd43ce3acea863de252b3764bfa70826eae2794b470093a1ebe2ba52243a92dd41228f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da6824d601a874afcb9c7e59141c2385ba933f22a347c4cf71071b33e3c6f278826f99338d6483903e72bfb3d1a83869cde9a489bd07aedb27948a7269a72906"} 01:55:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:55:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:55:37 INFO - Contents: 01:55:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f55f371da4bfc27757f2c16e04ed45e412f9bfdf58d9555b06fad62d52118a664b27b80d9e871ded0f3a0fb561bbe5a4b25a19da386aa61edc1af3a8401522c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79f14a23e89fbb311b95756073d4bd1041b9f8b466ff524df9f9d99e56c1c788f905148feaeb6d96879562ef3c6cfbbf394c3448e738f456953269e79c45a353", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9f97f61deddaf01cade7a9e724072a4177179531a2fbeae63bfd6da3afd43ce3acea863de252b3764bfa70826eae2794b470093a1ebe2ba52243a92dd41228f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da6824d601a874afcb9c7e59141c2385ba933f22a347c4cf71071b33e3c6f278826f99338d6483903e72bfb3d1a83869cde9a489bd07aedb27948a7269a72906"} 01:55:37 INFO - Running post-run listener: copy_logs_to_upload_dir 01:55:37 INFO - Copying logs to upload dir... 01:55:37 INFO - mkdir: /builds/slave/test/build/upload/logs 01:55:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.244620 ========= master_lag: 1.72 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 01:55:39.185640) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 01:55:39.188891) ========= 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.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f55f371da4bfc27757f2c16e04ed45e412f9bfdf58d9555b06fad62d52118a664b27b80d9e871ded0f3a0fb561bbe5a4b25a19da386aa61edc1af3a8401522c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79f14a23e89fbb311b95756073d4bd1041b9f8b466ff524df9f9d99e56c1c788f905148feaeb6d96879562ef3c6cfbbf394c3448e738f456953269e79c45a353", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9f97f61deddaf01cade7a9e724072a4177179531a2fbeae63bfd6da3afd43ce3acea863de252b3764bfa70826eae2794b470093a1ebe2ba52243a92dd41228f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da6824d601a874afcb9c7e59141c2385ba933f22a347c4cf71071b33e3c6f278826f99338d6483903e72bfb3d1a83869cde9a489bd07aedb27948a7269a72906"} build_url:https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011162 build_url: 'https://queue.taskcluster.net/v1/task/Wgcq1dGOTQiwY0JDpuM1Qw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f55f371da4bfc27757f2c16e04ed45e412f9bfdf58d9555b06fad62d52118a664b27b80d9e871ded0f3a0fb561bbe5a4b25a19da386aa61edc1af3a8401522c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/79f14a23e89fbb311b95756073d4bd1041b9f8b466ff524df9f9d99e56c1c788f905148feaeb6d96879562ef3c6cfbbf394c3448e738f456953269e79c45a353", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9f97f61deddaf01cade7a9e724072a4177179531a2fbeae63bfd6da3afd43ce3acea863de252b3764bfa70826eae2794b470093a1ebe2ba52243a92dd41228f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da6824d601a874afcb9c7e59141c2385ba933f22a347c4cf71071b33e3c6f278826f99338d6483903e72bfb3d1a83869cde9a489bd07aedb27948a7269a72906"}' ========= master_lag: 0.44 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 01:55:39.635960) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:55:39.636291) ========= 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.5ioKecm5TN/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.fw7buE50g3/Listeners TMPDIR=/var/folders/hd/dc4krl3j68d6tcd9m8k4vbjh00000w/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.005146 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 01:55:39.663544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 01:55:39.663810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 01:55:39.664112) ========= ========= Total master_lag: 2.28 =========