builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0263 starttime: 1491902323.04 results: success (0) buildid: 20170411004009 builduid: 8dd9541256fc47f4a03ce1506135667b revision: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.036429) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.036886) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.037161) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.066441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.066838) ========= 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.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-11 02:18:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 73.5M=0s 2017-04-11 02:18:43 (73.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104395 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.195801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.196112) ========= 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.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.031477 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:43.249981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 02:18:43.250325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7b0c6d30f63a80f1d67e37d991a807490abd06f9 --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 7b0c6d30f63a80f1d67e37d991a807490abd06f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-11 02:18:43,364 truncating revision to first 12 chars 2017-04-11 02:18:43,365 Setting DEBUG logging. 2017-04-11 02:18:43,365 attempt 1/10 2017-04-11 02:18:43,365 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7b0c6d30f63a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 02:18:44,401 unpacking tar archive at: mozilla-aurora-7b0c6d30f63a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405123 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 02:18:44.678898) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:44.679221) ========= script_repo_revision: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:44.679627) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:44.679891) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 02:18:44.699907) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 02:18:44.700297) ========= /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.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:18:44 INFO - MultiFileLogger online at 20170411 02:18:44 in /builds/slave/test 02:18:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:18:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:18:44 INFO - {'append_to_log': False, 02:18:44 INFO - 'base_work_dir': '/builds/slave/test', 02:18:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:18:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:18:44 INFO - 'branch': 'Mozilla-Aurora', 02:18:44 INFO - 'buildbot_json_path': 'buildprops.json', 02:18:44 INFO - 'config_files': ('talos/mac_config.py',), 02:18:44 INFO - 'default_actions': ('clobber', 02:18:44 INFO - 'read-buildbot-config', 02:18:44 INFO - 'download-and-extract', 02:18:44 INFO - 'populate-webroot', 02:18:44 INFO - 'create-virtualenv', 02:18:44 INFO - 'install', 02:18:44 INFO - 'run-tests'), 02:18:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:18:44 INFO - 'download_minidump_stackwalk': True, 02:18:44 INFO - 'download_symbols': 'ondemand', 02:18:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:18:44 INFO - 'tooltool.py': '/tools/tooltool.py', 02:18:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:18:44 INFO - '/tools/misc-python/virtualenv.py')}, 02:18:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:18:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:18:44 INFO - 'gecko_profile': False, 02:18:44 INFO - 'gecko_profile_interval': 0, 02:18:44 INFO - 'installer_path': 'installer.exe', 02:18:44 INFO - 'log_level': 'info', 02:18:44 INFO - 'log_name': 'talos', 02:18:44 INFO - 'log_to_console': True, 02:18:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:18:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:18:44 INFO - 'opt_config_files': (), 02:18:44 INFO - 'pip_index': False, 02:18:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:18:44 INFO - 'cmd': ('bash', 02:18:44 INFO - '-c', 02:18:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:18:44 INFO - 'enabled': True, 02:18:44 INFO - 'halt_on_failure': False, 02:18:44 INFO - 'name': 'check_screen_resolution'},), 02:18:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:18:44 INFO - 'cmd': ('bash', 02:18:44 INFO - '-c', 02:18:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:18:44 INFO - 'enabled': True, 02:18:44 INFO - 'halt_on_failure': False, 02:18:44 INFO - 'name': 'check_screen_resolution'},), 02:18:44 INFO - 'run_cmd_checks_enabled': True, 02:18:44 INFO - 'suite': 'dromaeojs-e10s', 02:18:44 INFO - 'system_bits': '32', 02:18:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:18:44 INFO - 'title': 't-yosemite-r7-0263', 02:18:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:18:44 INFO - 'use_talos_json': True, 02:18:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:18:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:18:44 INFO - 'work_dir': 'build'} 02:18:44 INFO - [mozharness: 2017-04-11 09:18:44.922293Z] Running clobber step. 02:18:44 INFO - Running pre-action listener: _resource_record_pre_action 02:18:44 INFO - Running main action method: clobber 02:18:44 INFO - rmtree: /builds/slave/test/build 02:18:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:18:47 INFO - Running post-action listener: _resource_record_post_action 02:18:47 INFO - [mozharness: 2017-04-11 09:18:47.437738Z] Finished clobber step (success) 02:18:47 INFO - [mozharness: 2017-04-11 09:18:47.437892Z] Running read-buildbot-config step. 02:18:47 INFO - Running pre-action listener: _resource_record_pre_action 02:18:47 INFO - Running main action method: read_buildbot_config 02:18:47 INFO - Using buildbot properties: 02:18:47 INFO - { 02:18:47 INFO - "project": "", 02:18:47 INFO - "product": "firefox", 02:18:47 INFO - "script_repo_revision": "production", 02:18:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:18:47 INFO - "repository": "", 02:18:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:18:47 INFO - "buildid": "20170411004009", 02:18:47 INFO - "stage_platform": "macosx64", 02:18:47 INFO - "basedir": "/builds/slave/test", 02:18:47 INFO - "nightly_build": "True", 02:18:47 INFO - "buildnumber": 103, 02:18:47 INFO - "slavename": "t-yosemite-r7-0263", 02:18:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:18:47 INFO - "platform": "yosemite_r7", 02:18:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:18:47 INFO - "branch": "mozilla-aurora", 02:18:47 INFO - "repo_path": "releases/mozilla-aurora", 02:18:47 INFO - "revision": "7b0c6d30f63a80f1d67e37d991a807490abd06f9", 02:18:47 INFO - "pgo_build": "False", 02:18:47 INFO - "builduid": "8dd9541256fc47f4a03ce1506135667b", 02:18:47 INFO - "slavebuilddir": "test" 02:18:47 INFO - } 02:18:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:18:47 INFO - Running post-action listener: _resource_record_post_action 02:18:47 INFO - [mozharness: 2017-04-11 09:18:47.441361Z] Finished read-buildbot-config step (success) 02:18:47 INFO - [mozharness: 2017-04-11 09:18:47.441493Z] Running download-and-extract step. 02:18:47 INFO - Running pre-action listener: _resource_record_pre_action 02:18:47 INFO - Running main action method: download_and_extract 02:18:47 INFO - mkdir: /builds/slave/test/build/tests 02:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:47 INFO - trying https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:18:48 INFO - Downloaded 1199 bytes. 02:18:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:18:48 INFO - Using the following test package requirements: 02:18:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:18:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:18:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:18:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'jsshell-mac.zip'], 02:18:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:18:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:18:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:18:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:18:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:18:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:18:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:18:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:18:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:18:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:18:48 INFO - Fetch https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:18:49 INFO - Expected file size: 51790442 02:18:49 INFO - Obtained file size: 51790442 02:19:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:19:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:19:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:19:05 INFO - Fetch https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:19:07 INFO - Expected file size: 51790442 02:19:07 INFO - Obtained file size: 51790442 02:19:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:19:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:19:22 INFO - Fetch https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:19:23 INFO - Expected file size: 14404424 02:19:23 INFO - Obtained file size: 14404424 02:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:24 INFO - trying https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:19:24 INFO - Downloading https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:19:26 INFO - Downloaded 74714804 bytes. 02:19:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:19:26 INFO - mkdir: /builds/slave/test/properties 02:19:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:19:26 INFO - Writing to file /builds/slave/test/properties/build_url 02:19:26 INFO - Contents: 02:19:26 INFO - build_url:https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:19:26 INFO - Running post-action listener: _resource_record_post_action 02:19:26 INFO - Running post-action listener: set_extra_try_arguments 02:19:26 INFO - [mozharness: 2017-04-11 09:19:26.865605Z] Finished download-and-extract step (success) 02:19:26 INFO - [mozharness: 2017-04-11 09:19:26.865729Z] Running populate-webroot step. 02:19:26 INFO - Running pre-action listener: _resource_record_pre_action 02:19:26 INFO - Running main action method: populate_webroot 02:19:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:19:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:19:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:19:26 INFO - '--tppagecycles', 02:19:26 INFO - '7'], 02:19:26 INFO - 'tests': ['tsvgm']}}, 02:19:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:19:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:19:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:19:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:19:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:19:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:19:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['damp', 'tps']}, 02:19:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'tests': ['damp', 'tps']}, 02:19:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['dromaeo_dom']}, 02:19:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:19:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['basic_compositor_video']}, 02:19:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:19:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'other-e10s': {'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['a11yr', 02:19:26 INFO - 'ts_paint', 02:19:26 INFO - 'tpaint', 02:19:26 INFO - 'sessionrestore', 02:19:26 INFO - 'sessionrestore_no_auto_restore', 02:19:26 INFO - 'tabpaint']}, 02:19:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['tsvgx', 02:19:26 INFO - 'tsvgr_opacity', 02:19:26 INFO - 'tart', 02:19:26 INFO - 'tscrollx', 02:19:26 INFO - 'cart', 02:19:26 INFO - 'tsvg_static']}, 02:19:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:19:26 INFO - 'tsvgr_opacity', 02:19:26 INFO - 'tart', 02:19:26 INFO - 'tscrollx', 02:19:26 INFO - 'cart', 02:19:26 INFO - 'tsvg_static']}, 02:19:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'talos_options': ['--disable-e10s'], 02:19:26 INFO - 'tests': ['tp5o']}, 02:19:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:19:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'talos_options': ['--disable-e10s', 02:19:26 INFO - '--xperf_path', 02:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:19:26 INFO - 'tests': ['tp5n']}, 02:19:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:19:26 INFO - 'talos_options': ['--xperf_path', 02:19:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:19:26 INFO - 'tests': ['tp5n']}}, 02:19:26 INFO - 'talos.zip': {'path': '', 02:19:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:19:26 INFO - Running post-action listener: _resource_record_post_action 02:19:26 INFO - [mozharness: 2017-04-11 09:19:26.884279Z] Finished populate-webroot step (success) 02:19:26 INFO - [mozharness: 2017-04-11 09:19:26.884420Z] Running create-virtualenv step. 02:19:26 INFO - Running pre-action listener: _resource_record_pre_action 02:19:26 INFO - Running main action method: create_virtualenv 02:19:26 INFO - Creating virtualenv /builds/slave/test/build/venv 02:19:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:19:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:19:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:19:27 INFO - Using real prefix '/tools/python27' 02:19:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:19:27 INFO - Installing distribute.............................................................................................................................................................................................done. 02:19:30 INFO - Installing pip.................done. 02:19:30 INFO - Return code: 0 02:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:19:30 INFO - Reading from file tmpfile_stdout 02:19:30 INFO - Output received: 02:19:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:19:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:30 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:30 INFO - 'HOME': '/Users/cltbld', 02:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:30 INFO - 'LOGNAME': 'cltbld', 02:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:30 INFO - 'MOZ_NO_REMOTE': '1', 02:19:30 INFO - 'NO_EM_RESTART': '1', 02:19:30 INFO - 'PAGER': '/bin/cat', 02:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:30 INFO - 'PWD': '/builds/slave/test', 02:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:30 INFO - 'SHELL': '/bin/bash', 02:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:30 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:30 INFO - 'USER': 'cltbld', 02:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:30 INFO - 'XPC_FLAGS': '0x0', 02:19:30 INFO - 'XPC_SERVICE_NAME': '0', 02:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:19:31 INFO - Cleaning up... 02:19:31 INFO - Return code: 0 02:19:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:31 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:31 INFO - 'HOME': '/Users/cltbld', 02:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:31 INFO - 'LOGNAME': 'cltbld', 02:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:31 INFO - 'MOZ_NO_REMOTE': '1', 02:19:31 INFO - 'NO_EM_RESTART': '1', 02:19:31 INFO - 'PAGER': '/bin/cat', 02:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:31 INFO - 'PWD': '/builds/slave/test', 02:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:31 INFO - 'SHELL': '/bin/bash', 02:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:31 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:31 INFO - 'USER': 'cltbld', 02:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:31 INFO - 'XPC_FLAGS': '0x0', 02:19:31 INFO - 'XPC_SERVICE_NAME': '0', 02:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:31 INFO - Downloading/unpacking psutil>=3.1.1 02:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:34 INFO - Installing collected packages: psutil 02:19:34 INFO - Running setup.py install for psutil 02:19:35 INFO - building 'psutil._psutil_osx' extension 02:19:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:19:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:19:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:19:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:19:35 INFO - building 'psutil._psutil_posix' extension 02:19:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:19:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:19:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:19:35 INFO - ^ 02:19:35 INFO - 1 warning generated. 02:19:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:19:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:36 INFO - Successfully installed psutil 02:19:36 INFO - Cleaning up... 02:19:36 INFO - Return code: 0 02:19:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:36 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:19:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:36 INFO - 'HOME': '/Users/cltbld', 02:19:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:36 INFO - 'LOGNAME': 'cltbld', 02:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:36 INFO - 'MOZ_NO_REMOTE': '1', 02:19:36 INFO - 'NO_EM_RESTART': '1', 02:19:36 INFO - 'PAGER': '/bin/cat', 02:19:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:36 INFO - 'PWD': '/builds/slave/test', 02:19:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:36 INFO - 'SHELL': '/bin/bash', 02:19:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:36 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:36 INFO - 'USER': 'cltbld', 02:19:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:36 INFO - 'XPC_FLAGS': '0x0', 02:19:36 INFO - 'XPC_SERVICE_NAME': '0', 02:19:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:19:39 INFO - Installing collected packages: mozsystemmonitor 02:19:39 INFO - Running setup.py install for mozsystemmonitor 02:19:39 INFO - Successfully installed mozsystemmonitor 02:19:39 INFO - Cleaning up... 02:19:39 INFO - Return code: 0 02:19:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:40 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:19:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:19:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:40 INFO - 'HOME': '/Users/cltbld', 02:19:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:40 INFO - 'LOGNAME': 'cltbld', 02:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:40 INFO - 'MOZ_NO_REMOTE': '1', 02:19:40 INFO - 'NO_EM_RESTART': '1', 02:19:40 INFO - 'PAGER': '/bin/cat', 02:19:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:40 INFO - 'PWD': '/builds/slave/test', 02:19:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:40 INFO - 'SHELL': '/bin/bash', 02:19:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:40 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:40 INFO - 'USER': 'cltbld', 02:19:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:40 INFO - 'XPC_FLAGS': '0x0', 02:19:40 INFO - 'XPC_SERVICE_NAME': '0', 02:19:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:40 INFO - Downloading/unpacking jsonschema==2.5.1 02:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:19:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:44 INFO - Downloading functools32-3.2.3-2.tar.gz 02:19:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:19:44 INFO - warning: no files found matching '*.txt' 02:19:44 INFO - no previously-included directories found matching 'build' 02:19:44 INFO - no previously-included directories found matching 'dist' 02:19:44 INFO - no previously-included directories found matching '.git*' 02:19:44 INFO - Installing collected packages: jsonschema, functools32 02:19:44 INFO - Running setup.py install for functools32 02:19:44 INFO - warning: no files found matching '*.txt' 02:19:44 INFO - no previously-included directories found matching 'build' 02:19:44 INFO - no previously-included directories found matching 'dist' 02:19:44 INFO - no previously-included directories found matching '.git*' 02:19:44 INFO - Successfully installed jsonschema functools32 02:19:44 INFO - Cleaning up... 02:19:44 INFO - Return code: 0 02:19:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:44 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:44 INFO - 'HOME': '/Users/cltbld', 02:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:44 INFO - 'LOGNAME': 'cltbld', 02:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:44 INFO - 'MOZ_NO_REMOTE': '1', 02:19:44 INFO - 'NO_EM_RESTART': '1', 02:19:44 INFO - 'PAGER': '/bin/cat', 02:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:44 INFO - 'PWD': '/builds/slave/test', 02:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:44 INFO - 'SHELL': '/bin/bash', 02:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:44 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:44 INFO - 'USER': 'cltbld', 02:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:44 INFO - 'XPC_FLAGS': '0x0', 02:19:44 INFO - 'XPC_SERVICE_NAME': '0', 02:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:19:44 INFO - Cleaning up... 02:19:44 INFO - Return code: 0 02:19:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:44 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:44 INFO - 'HOME': '/Users/cltbld', 02:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:44 INFO - 'LOGNAME': 'cltbld', 02:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:44 INFO - 'MOZ_NO_REMOTE': '1', 02:19:44 INFO - 'NO_EM_RESTART': '1', 02:19:44 INFO - 'PAGER': '/bin/cat', 02:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:44 INFO - 'PWD': '/builds/slave/test', 02:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:44 INFO - 'SHELL': '/bin/bash', 02:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:44 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:44 INFO - 'USER': 'cltbld', 02:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:44 INFO - 'XPC_FLAGS': '0x0', 02:19:44 INFO - 'XPC_SERVICE_NAME': '0', 02:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:44 INFO - Downloading/unpacking blobuploader==1.2.4 02:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:47 INFO - Downloading blobuploader-1.2.4.tar.gz 02:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:19:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:19:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:48 INFO - Downloading docopt-0.6.1.tar.gz 02:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:19:48 INFO - Installing collected packages: blobuploader, requests, docopt 02:19:48 INFO - Running setup.py install for blobuploader 02:19:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:19:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:19:49 INFO - Running setup.py install for requests 02:19:49 INFO - Running setup.py install for docopt 02:19:49 INFO - Successfully installed blobuploader requests docopt 02:19:49 INFO - Cleaning up... 02:19:49 INFO - Return code: 0 02:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:49 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:49 INFO - 'HOME': '/Users/cltbld', 02:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:49 INFO - 'LOGNAME': 'cltbld', 02:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:49 INFO - 'MOZ_NO_REMOTE': '1', 02:19:49 INFO - 'NO_EM_RESTART': '1', 02:19:49 INFO - 'PAGER': '/bin/cat', 02:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:49 INFO - 'PWD': '/builds/slave/test', 02:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:49 INFO - 'SHELL': '/bin/bash', 02:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:49 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:49 INFO - 'USER': 'cltbld', 02:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:49 INFO - 'XPC_FLAGS': '0x0', 02:19:49 INFO - 'XPC_SERVICE_NAME': '0', 02:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-lr2aPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Vm25Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-n8S1v9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-sPbxYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-1x35qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-KA1Ppe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-0vjF8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-A7L1mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-N7Cr0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:50 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BfFRAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-jxoMV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3O76xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rrvFFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-luYAoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-VghWAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-PWAuZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:51 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-DyjPdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:19:51 INFO - Running setup.py install for manifestparser 02:19:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:19:51 INFO - Running setup.py install for mozcrash 02:19:52 INFO - Running setup.py install for mozdebug 02:19:52 INFO - Running setup.py install for mozdevice 02:19:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Running setup.py install for mozfile 02:19:52 INFO - Running setup.py install for mozhttpd 02:19:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Running setup.py install for mozinfo 02:19:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Running setup.py install for mozInstall 02:19:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:19:52 INFO - Running setup.py install for mozleak 02:19:53 INFO - Running setup.py install for mozlog 02:19:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Running setup.py install for moznetwork 02:19:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Running setup.py install for mozprocess 02:19:53 INFO - Running setup.py install for mozprofile 02:19:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Running setup.py install for mozrunner 02:19:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:19:53 INFO - Running setup.py install for mozscreenshot 02:19:54 INFO - Running setup.py install for moztest 02:19:54 INFO - Running setup.py install for mozversion 02:19:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:19:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:19:54 INFO - Cleaning up... 02:19:54 INFO - Return code: 0 02:19:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19: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')]} 02:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19: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')]} 02:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:54 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:54 INFO - 'HOME': '/Users/cltbld', 02:19:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:54 INFO - 'LOGNAME': 'cltbld', 02:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:54 INFO - 'MOZ_NO_REMOTE': '1', 02:19:54 INFO - 'NO_EM_RESTART': '1', 02:19:54 INFO - 'PAGER': '/bin/cat', 02:19:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:54 INFO - 'PWD': '/builds/slave/test', 02:19:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:54 INFO - 'SHELL': '/bin/bash', 02:19:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:54 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:54 INFO - 'USER': 'cltbld', 02:19:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:54 INFO - 'XPC_FLAGS': '0x0', 02:19:54 INFO - 'XPC_SERVICE_NAME': '0', 02:19:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:54 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-tdRl37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:54 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-4iGBIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:54 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BvJj0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:19:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:54 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-5HtG8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-a7RS9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-C0lX3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xPvlYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-rez_Rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-mgX6zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-4en81Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-i32yjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-z8vNcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YcqFDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:55 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-v5FvCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:56 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-l2d4Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:56 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-nT6F5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:56 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-T1ftbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:19:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:58 INFO - Downloading blessings-1.6.tar.gz 02:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:19:59 INFO - Installing collected packages: blessings 02:19:59 INFO - Running setup.py install for blessings 02:19:59 INFO - Successfully installed blessings 02:19:59 INFO - Cleaning up... 02:19:59 INFO - Return code: 0 02:19:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:59 INFO - Reading from file tmpfile_stdout 02:19:59 INFO - Current package versions: 02:19:59 INFO - blessings == 1.6 02:19:59 INFO - blobuploader == 1.2.4 02:19:59 INFO - docopt == 0.6.1 02:19:59 INFO - functools32 == 3.2.3-2 02:19:59 INFO - jsonschema == 2.5.1 02:19:59 INFO - manifestparser == 1.1 02:19:59 INFO - mozInstall == 1.12 02:19:59 INFO - mozcrash == 1.0 02:19:59 INFO - mozdebug == 0.1 02:19:59 INFO - mozdevice == 0.49 02:19:59 INFO - mozfile == 1.2 02:19:59 INFO - mozhttpd == 0.7 02:19:59 INFO - mozinfo == 0.9 02:19:59 INFO - mozleak == 0.1 02:19:59 INFO - mozlog == 3.4 02:19:59 INFO - moznetwork == 0.27 02:19:59 INFO - mozprocess == 0.25 02:19:59 INFO - mozprofile == 0.28 02:19:59 INFO - mozrunner == 6.13 02:19:59 INFO - mozscreenshot == 0.1 02:19:59 INFO - mozsystemmonitor == 0.3 02:19:59 INFO - moztest == 0.8 02:19:59 INFO - mozversion == 1.4 02:19:59 INFO - psutil == 3.1.1 02:19:59 INFO - requests == 1.2.3 02:19:59 INFO - wsgiref == 0.1.2 02:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:59 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:59 INFO - 'HOME': '/Users/cltbld', 02:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:59 INFO - 'LOGNAME': 'cltbld', 02:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:59 INFO - 'MOZ_NO_REMOTE': '1', 02:19:59 INFO - 'NO_EM_RESTART': '1', 02:19:59 INFO - 'PAGER': '/bin/cat', 02:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:59 INFO - 'PWD': '/builds/slave/test', 02:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:59 INFO - 'SHELL': '/bin/bash', 02:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:59 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:59 INFO - 'USER': 'cltbld', 02:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:59 INFO - 'XPC_FLAGS': '0x0', 02:19:59 INFO - 'XPC_SERVICE_NAME': '0', 02:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:19:59 INFO - Cleaning up... 02:19:59 INFO - Return code: 0 02:19:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:59 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 0x10e4cbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff320c54e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, '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.FLZjTyVpAE/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.00VHRaz1WG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:19:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:19:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:59 INFO - 'HOME': '/Users/cltbld', 02:19:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:59 INFO - 'LOGNAME': 'cltbld', 02:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:59 INFO - 'MOZ_NO_REMOTE': '1', 02:19:59 INFO - 'NO_EM_RESTART': '1', 02:19:59 INFO - 'PAGER': '/bin/cat', 02:19:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:59 INFO - 'PWD': '/builds/slave/test', 02:19:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:59 INFO - 'SHELL': '/bin/bash', 02:19:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:19:59 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:19:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:59 INFO - 'USER': 'cltbld', 02:19:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:59 INFO - 'XPC_FLAGS': '0x0', 02:19:59 INFO - 'XPC_SERVICE_NAME': '0', 02:19:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:20:00 INFO - Cleaning up... 02:20:00 INFO - Return code: 0 02:20:00 INFO - Running post-action listener: _resource_record_post_action 02:20:00 INFO - Running post-action listener: _start_resource_monitoring 02:20:00 INFO - Starting resource monitoring. 02:20:00 INFO - [mozharness: 2017-04-11 09:20:00.189621Z] Finished create-virtualenv step (success) 02:20:00 INFO - [mozharness: 2017-04-11 09:20:00.190580Z] Running install step. 02:20:00 INFO - Running pre-action listener: _resource_record_pre_action 02:20:00 INFO - Running main action method: install 02:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:20:00 INFO - Reading from file tmpfile_stdout 02:20:00 INFO - Detecting whether we're running mozinstall >=1.0... 02:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:20:00 INFO - Reading from file tmpfile_stdout 02:20:00 INFO - Output received: 02:20:00 INFO - Usage: mozinstall [options] installer 02:20:00 INFO - Options: 02:20:00 INFO - -h, --help show this help message and exit 02:20:00 INFO - -d DEST, --destination=DEST 02:20:00 INFO - Directory to install application into. [default: 02:20:00 INFO - "/builds/slave/test"] 02:20:00 INFO - --app=APP Application being installed. [default: firefox] 02:20:00 INFO - mkdir: /builds/slave/test/build/application 02:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:20:20 INFO - Reading from file tmpfile_stdout 02:20:20 INFO - Output received: 02:20:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:20:20 INFO - Running post-action listener: _resource_record_post_action 02:20:20 INFO - [mozharness: 2017-04-11 09:20:20.518594Z] Finished install step (success) 02:20:20 INFO - [mozharness: 2017-04-11 09:20:20.518761Z] Running run-tests step. 02:20:20 INFO - Running pre-action listener: _resource_record_pre_action 02:20:20 INFO - Running main action method: run_tests 02:20:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:20:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:20:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:20:20 INFO - 2017-04-11 02:20:20.532 screenresolution[1623:7455] starting screenresolution argv=screenresolution get 02:20:20 INFO - 2017-04-11 02:20:20.535 screenresolution[1623:7455] Display 0: 1600x1200x32@60 02:20:20 INFO - 2017-04-11 02:20:20.541 screenresolution[1624:7457] starting screenresolution argv=screenresolution list 02:20:20 INFO - Available Modes on Display 0 02:20:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:20:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:20:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:20:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:20:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:20:20 INFO - Intel Iris: 02:20:20 INFO - Chipset Model: Intel Iris 02:20:20 INFO - Type: GPU 02:20:20 INFO - Bus: Built-In 02:20:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:20:20 INFO - Vendor: Intel (0x8086) 02:20:20 INFO - Device ID: 0x0a2e 02:20:20 INFO - Revision ID: 0x0009 02:20:20 INFO - Displays: 02:20:20 INFO - Display: 02:20:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:20:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:20:20 INFO - Display Serial Number: 1600x1200 60 02:20:20 INFO - Main Display: Yes 02:20:20 INFO - Mirror: Off 02:20:20 INFO - Online: Yes 02:20:20 INFO - Rotation: Supported 02:20:20 INFO - Return code: 0 02:20:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:20:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:20:20 INFO - Python 2.7.3 02:20:20 INFO - Return code: 0 02:20:20 INFO - grabbing minidump binary from tooltool 02:20:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:20:20 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 0x10e2a9d30>, '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 0x10e1d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e29ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:20:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:20:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:20:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:20:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:20:20 INFO - Return code: 0 02:20:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:20:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:20:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:20:20 INFO - ENV: RUST_BACKTRACE is now 1 02:20:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:20:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:20:20 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/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:20:20 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/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:20:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FLZjTyVpAE/Render', 02:20:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:20:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:20:20 INFO - 'HOME': '/Users/cltbld', 02:20:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:20:20 INFO - 'LOGNAME': 'cltbld', 02:20:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:20:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:20 INFO - 'MOZ_NO_REMOTE': '1', 02:20:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:20:20 INFO - 'NO_EM_RESTART': '1', 02:20:20 INFO - 'PAGER': '/bin/cat', 02:20:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:20 INFO - 'PWD': '/builds/slave/test', 02:20:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:20:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:20:20 INFO - 'RUST_BACKTRACE': '1', 02:20:20 INFO - 'SHELL': '/bin/bash', 02:20:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.00VHRaz1WG/Listeners', 02:20:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 02:20:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:20:20 INFO - 'USER': 'cltbld', 02:20:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:20:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:20 INFO - 'XPC_FLAGS': '0x0', 02:20:20 INFO - 'XPC_SERVICE_NAME': '0', 02:20:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:20:20 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/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:20:21 INFO - mozversion application_buildid: 20170411004009 02:20:21 INFO - mozversion application_changeset: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 02:20:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:20:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:20:21 INFO - mozversion application_name: Firefox 02:20:21 INFO - mozversion application_remotingname: firefox-dev 02:20:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:20:21 INFO - mozversion application_vendor: Mozilla 02:20:21 INFO - mozversion application_version: 54.0a2 02:20:21 INFO - mozversion platform_buildid: 20170411004009 02:20:21 INFO - mozversion platform_changeset: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 02:20:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:20:21 INFO - mozversion platform_version: 54.0a2 02:20:21 INFO - using testdate: 1491902421 02:20:21 INFO - actual date: 1491902421 02:20:21 INFO - starting webserver on 'localhost:49241' 02:20:21 INFO - SUITE-START | Running 2 tests 02:20:21 INFO - TEST-START | dromaeo_css 02:20:21 INFO - operating with platform_type : mac_ 02:20:21 INFO - Initialising browser for dromaeo_css test... 02:20:21 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpLJnFoP/profile http://localhost:49241/getInfo.html) 02:20:24 INFO - PROCESS | 1634 | 2017-04-11 02:20:24.330 plugin-container[1637:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e47, name = 'com.apple.tsm.portname' 02:20:24 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:20:24 INFO - PROCESS | 1634 | 2017-04-11 02:20:24.331 plugin-container[1637:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x961b, name = 'com.apple.CFPasteboardClient' 02:20:24 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:20:24 INFO - PROCESS | 1634 | 2017-04-11 02:20:24.331 plugin-container[1637:7645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:20:24 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 02:20:24 INFO - PROCESS | 1634 | colorDepth:24 02:20:24 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 02:20:24 INFO - PROCESS | 1634 | __metrics 02:20:24 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:20:25 INFO - TEST-INFO | 1634: exit 0 02:20:25 INFO - Browser initialized. 02:20:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:20:25 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpLJnFoP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:20:27 INFO - PROCESS | 1638 | 2017-04-11 02:20:27.484 plugin-container[1639:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 02:20:27 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:20:27 INFO - PROCESS | 1638 | 2017-04-11 02:20:27.485 plugin-container[1639:7785] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 02:20:27 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:20:27 INFO - PROCESS | 1638 | 2017-04-11 02:20:27.485 plugin-container[1639:7785] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:20:37 INFO - PROCESS | 1638 | RSS: Main: 220651520 02:20:37 INFO - PROCESS | 1638 | 02:23:41 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:23:42 INFO - PROCESS | 1638 | RSS: Main: 238309376 02:23:42 INFO - PROCESS | 1638 | 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:25:25 INFO - PROCESS | 1638 | 02:25:25 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:25:25 INFO - PROCESS | 1638 | Full stack: 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:25:25 INFO - PROCESS | 1638 | 02:25:25 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:25:25 INFO - PROCESS | 1638 | Full stack: 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:25:25 INFO - PROCESS | 1638 | 02:25:25 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:25:25 INFO - PROCESS | 1638 | Full stack: 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | ************************* 02:25:25 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 02:25:25 INFO - PROCESS | 1638 | 02:25:25 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:25:25 INFO - PROCESS | 1638 | Full stack: 02:25:25 INFO - PROCESS | 1638 | ************************* 02:26:45 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:26:45 INFO - PROCESS | 1638 | RSS: Main: 249208832 02:26:45 INFO - PROCESS | 1638 | 02:29:47 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:29:48 INFO - PROCESS | 1638 | RSS: Main: 256618496 02:29:48 INFO - PROCESS | 1638 | 02:32:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:32:50 INFO - PROCESS | 1638 | RSS: Main: 252862464 02:32:50 INFO - PROCESS | 1638 | 02:35:53 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:35:54 INFO - PROCESS | 1638 | RSS: Main: 257892352 02:35:54 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:38:57 INFO - PROCESS | 1638 | RSS: Main: 254185472 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | __start_tp_report 02:38:57 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 02:38:57 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 02:38:57 INFO - PROCESS | 1638 | |i|pagename|runs| 02:38:57 INFO - PROCESS | 1638 | |0;dojo.html;3480.5997310309313;3539.6991255743246;3540.6636369544885;3551.431770916653;3558.7330950178743;5473.972630136849;5745.454181852722;6145.354737752533;6157.230346206724;6170.136180934662;6109.663968481732;6194.070889366597;6205.782797602084;6212.78255261066;6213.502919766419;5453.481919217678;5549.278593782803;5569.470900264469;5570.944290557103;5646.604737668365;5616.803411880585;5623.690697011662;5634.211210430543;5641.266635337401;5671.943280567203;5388.084025715618;5400.973814975162;5515.889682206353;5623.662580245178;5636.633618814764;5615.831525054255;5615.943840561624;5626.2967129108865;5652.971735141339;5669.206311116447;5432.239486471897;5513.5864810139;5528.834134975917;5529.834104976857;5548.91676624851;5340.172273297645;5916.940830591682;5926.1110833374905;6108.602940808833;6236.719347629368;6378.234611846562;6419.935800641981;6426.778912006704;6498.122753428274;6530.346965303432;6579;6580.243272023683;6583.769568065095;6588.505862060317;6594.670266486656;6209.565330426872;6230.937690623035;6250.1874756281395;6257.374262573751;6282.905756413657;5384.703841288767;5475.589330800126;6145.324014358417;6617.8014659560295;6692.096566963495;6453.967730161319;6458.709358078898;6462.159919210472;6473;6490.8052758417325;6505.60966342021;6611.438027767694;6612.966935165389;6617;6622.701978410983;6265.8746825063245;6300.9684951574945;6319.178506794087;6393.552451328363;6510.25132109804;6293.748250070037;6364.554481186273;6405.654812489336;6438.130852334969;6501.934980650228;5187.455317191716;5258.342707161605;5261.342332208474;5264.842054738364;5278.234655974862;4652.62778977681;4678.3216433616935;4678.461976872635;4696.530346965276;4743.905121897543;6049.78825741097;6098.939010609926;6298.212723409574;6298.370162983665;6319.05214217871;6465.288818229991;6465.288818229991;6518.5762925410645;6521.576097553644;6550.017497375432;5322.148456246982;5372.495701203669;5891.49922256613;6185.721642526097;6282.528810339243;5611.466910643482;5794.8551286217335;5802.970985145047;5804.79683211094;5809;5602.775888964396;6565.540412171103;6631.403173714292;6741.426978706865;6829.282925292773;6509.8698026040165;6531.640759758259;6566.967165164144;6575.375339342755;6597.637129957899;6787.694553744995;6801;6818.727250909908;6823.147106611674;6823.965880170567;5358;6288.182536270256;6318.368163183645;6376.74493020274;6452.903206452001;6471.870562588816;6552.934470655233;6555.475561954937;6579.934200657932;6599.373059559334;5515.841673248663;6389.744410223539;6390.009548519987;6411.967940160456;6419.037144428373;5697.715114244188;6268.153799237227;6311.842203944938;6416.550841441241;6432.678366081584;6000.489958353589;6227.813165605039;6362.618242905441;6376.330485298977;6387.073874288295;6249.531285153723;6368.713407896748;6388.776392826228;6415.1981002374705;6457;6214.8135555933395;6287.371262873676;6289.968550157403;6310.49516038725;6341.397567231105 02:38:57 INFO - PROCESS | 1638 | |1;ext.html;732.9157146928105;799.0850476204746;817.824167803922;950.743299309187;958.6117622362937;38099.4285085724;38875.02812429687;40571.188576228495;40583;40622.7968860156;31323;39106.21787564247;39108.60891391085;39236.21527569447;39246;7910.644021019039;8016.438849280553;10425.635102771404;10721.463926803628;10764.73088172798;10473.790524189511;10521.631742889;10561.788764224711;10595;10610.469476526143;10578.576856925714;10600.734981625445;10606.575736970512;10607.098396636295;10609.469526523642;10561.260711750181;10564.524596393143;10591.099756520705;10599.629012984547;10623.84064239037;10299.742506437324;10362.740931476697;10371.222158338154;10373.84439233412;10397.012283833024;34202.657973420195;34322.14194645129;34354;34355.82822085899;34526.96419107431;3154.8895788647287;3662.34077865984;3763.5295588051495;3764.3976963685955;3774.924501509982;24854.254372368858;25424.872875635505;26358.472830543473;26428.73571264282;26558.672066396604;2832.2636114610145;2889.552119421493;3003.8798448062053;3018.351054523288;3018.622672165979;346721;351249;352277;352382;352858.2357088224;43592.564074359485;44227;44387.11225775466;44397;44568.55431445709;46234.30648540275;46267;46295.5370446298;46437.53562464332;46577.06845863064;11282.435878206057;13925.95555333354;14056.929715351562;14057.43770249199;14076.225807580477;10043.69868903934;11984.940075299568;12190;12192.81710774339;12203.328816914978;10225.539850706737;11434.142439317084;11822.586209482799;11941.76116477683;11951.880481195076;4942.9258561121615;5674.120511320752;5694.402087780806;5718.628289161191;5728.05487094633;935.9578818953163;952.1716106986827;960.6157536985262;982.8181786369545;986.1765425869336;27344.58983115255;27965.72034279631;28057.158285251477;28199.91913786988;28652.71347286542;898.910108989109;996.9482196282991;1045.242199405425;1045.900639439252;1079.83262594298;11168.441577921072;11410.144239182095;14473.986820922435;14607.92696036513;14782;492797;495070;495382;495401;496716;46228.5377146231;46573.53426465692;46642;48434.78913027202;49953.75023124861;1366.1393322206945;1426.2868565717142;1436.3033928544648;1436.7485690004207;1441.076163321977;25361.605111719033;26439.33901652474;26816.025434043026;27123.186304410898;27340.453190935674;40350.39474407945;40523.98690032773;40556.79721601373;40684.982875427166;40737;443700;444145;445397;447751;448036;29557.408851823275;29563.704362956094;29710.405791884474;29712.405751885275;29923;37301.62698372982;37611.81194094012;37651.81174094112;37855;37940.810295948344;26317.21048368552;27468.587971180048;27653;27969.44061118807;28143.014994475096;20303.69544456805;27150.864245678644;27558.448831022866;27702.861485692443;27796.44407111887;31369.686303136677;31698.207544811565;31759;31800.681993179773;31905.8404707975;21768.67346989828;25055.248342549752;26314.86842565775;26812.32969175708;27145.86427067852;17530.824691752918;21396.465088372915;23059.769402305763;23281.417964551023;23954.760452395254 02:38:57 INFO - PROCESS | 1638 | |2;jquery.html;423.28042328042335;433.1186036415894;435.9825606975719;446.510886194541;447.05894092934375;19058.714119288186;20170.89914550427;21330.57338853222;21409.67885481719;21475.57048859022;20509.897450512726;21566.02952867121;21874.562508749856;21906.342809715694;22141.446463838412;6518.902216466769;6703.363180497845;6728.293529179439;6778.694958726845;6778.864422711567;6403.903941440882;6580.638064906429;6603.70283337251;6633.966830165842;6665.366790154952;6323.841903952392;6583.341665833426;6643.302453242412;6658.434033107192;6676.332366763309;6560.901586476207;6592.143021407234;6603.108580341641;6621.006848972645;6653.800385988404;6149.954507733652;6255.624662520263;6389.584676995981;6420.903686444707;6482.286948435668;21607.243746468797;23934.7606523936;25368.23895283126;26243;26728.331791705168;2845.3455705187716;2967.213688372583;2973.6431628204537;2974.628171478565;2981.4633365994114;15617.297221625055;15667.059976401339;15954.601134971603;16052.59868503285;16253.593660158473;1955.5697746495748;1961.3723608445302;1993.8903360315178;2000.5290820426549;2006.1574138861567;147084;151042;151727.24136379358;176920;177179;15356;22036;22137.89310534467;22207.55584888293;22257.443563910707;31850.840745796588;32661.020169394957;33000.174995624824;33013.83493082519;33143.502847457305;10553.894461055292;10573.207009474167;10693.465326733787;10722.249442538947;10789.784204315869;8411.957940210383;8841.292696584258;8956.373053886166;9120.179183873483;9123.589438475285;9367.859482107773;9578.233741300679;9599.568019439143;9698.951505242429;9706.029397060238;3229.531717900891;4217.34631132175;4285.357196420562;4308.849190278325;4323.135372925428;886.5389997201548;896.2695403246464;899.0874262623443;905.330055758747;932.6362718539775;20810.687839682418;20956.476088097614;21144.6255993363;21250.468738281357;21318;827.2430725885868;836.180543067797;885.8804061451622;917.060013486182;954.4178051388648;10161.847572286422;10870.130389568816;10901.890981090246;10911.127109831194;10932.508037138348;732120;738824;738908;745322;758034;117705.41147294208;131686;151449;155745;167197;1173.5892437646926;1191.9821202681967;1208.7763763703742;1218.737971336167;1219.6828824505515;13500.797488037884;19250.8074919249;19725;19740.90129549343;19846.702299465807;59397.703011484664;64615;66717.99923001062;66860.6656966712;66884;74718;92809;92968.87107014067;94683.57974630248;94805.05194948238;10758.40828754426;11556;11578.247413918069;11764.176552634355;11816.291022538675;12775.744485110059;12787.552435665079;13022.218666880017;13030.283334416697;13231.603051908458;9262.44425334482;11129.332240065622;11201.991820736173;11427.371494567878;11434.714132146762;10469;10487.213458990758;10597.682069537927;10700.946495267473;10789.73025674333;11127.332360058099;11241.71895702614;11389.943050285026;11436.1422893285;11501.367424791382;11615.825762613737;11623.360715160747;11912.642620721392;11913.583024594098;11916.16586838913;10860.797047164979;11165.944170279097;11184.664460066211;11211.831822522505;11429.142814288794 02:38:57 INFO - PROCESS | 1638 | |3;mootools.html;1407.3455843032987;1435.9497417590387;1491.522712731925;1532.2874863188622;1536.3624096000158;9433.198178154847;9682.225421966243;10647;11227.157963152766;11276.492557834896;10669.466526673674;11106;11156.16328775343;11211.439428028607;11231.438428078603;2388.3909603051197;2426.2478631624163;2431.0032886516533;2434.9513009739794;2437.5003124359464;2368.95262094758;2402.267308470916;2413.698287714036;2416.093964763214;2417.9758202418016;2389.438481956739;2395.9520809583796;2398.352444839892;2412.589859723851;2429.2712186344115;2364.207990323244;2374.5013547155118;2376.6791483149814;2383;2386.200622791367;2368.585497537941;2375.869327187004;2386.844855084414;2387.403149212697;2392.485615592656;11132.998030177323;11323.773524529546;11327.830082548768;11338.659840204808;11396.943015284953;5734.340550836666;5828.242328497268;5892.234009578728;5918.3785702501045;5934.97032514839;3505.386557352453;3570.4644303354444;3592.6946209572216;3646.32542979547;3660.103274697716;1129.7458071933831;1147;1186.1400484648648;1190.6190019193862;1207.885250901163;15334.539963801104;15494.30275637599;15516.689666206725;15550.300236489385;15555.922220388939;4690.95309046912;4883.316335713003;4920;4955.578775804097;5016.473270306638;13053.673658158623;13220;13223.73552528944;13242.602721918358;13285.3357332133;5389.595780316414;5819.243498345188;6012.518998480198;6026.246719160105;6052.6671033092725;3995.5405128410025;4312.633426158812;5241.9213711794355;5318.601104917147;5337.599680024014;3242.5460435539044;3933.7246392752804;4024.1549274652652;4048.595140485928;4086.5096188457574;1129.0910816792561;1132.0773569540797;1132.9206955513034;1141.7668917569172;1145.873953865074;926.7822061815468;927.8561822917459;937.7843096087938;940.1867384712175;961.1733908838394;6166.660833654102;6378.266499352634;6580.5722628029025;6727;6920.30796920314;908.981820363589;1000.7798284377425;1025.6512785652915;1028.6142696488816;1038.2732087538752;4233.216854881795;4297.52727200014;4362.411074504965;4468.128714900628;4484.417025786692;12371.6907077322;13301.534446294527;13357.46570137203;13960.58118256454;13990.510332138327;13472;13669.179849209078;13775.724485510234;13860.89112870968;13890.472048074704;10104.949475252577;10110.191184705205;10240.897591023995;10269.948650256702;10343.224258180517;6542.803715888531;6547.541672082908;6605;6671.114076050592;6720.596764193969;12127.636170914886;12259.509619615472;12304.384780761106;12312.199707019015;12444.377781111089;12525.812112818132;12680.619581412573;12700.809487857874;12759.74480510366;12762.872371276168;7461.925380746123;8352.707655232038;8383.455075420323;8386.538740369338;8541.316694664536;10222.386656800616;10230.232732545237;10317.071463568314;10330.948345258226;10342.948285258826;8589.8711519326;8609.440386374865;8619.956900215459;8631.26634236097;8631.26634236097;6948.478864085315;8393;8403.537805420761;8469.957650211709;8524.957375213333;7050.541714788523;7804.882926756217;7805.297523222937;7847.176046515034;7880.724174653859;5597.384287728428;5629.437056294338;5659.54723622101;5767.480926716616;5819.447152520504;5049.8737531562;5891.49922256613;5964.940350596438;5979.611325263844;6110.847228819315 02:38:57 INFO - PROCESS | 1638 | |4;prototype.html;343.4556228378021;346.19164251472813;357.4316836230393;365.73666959788943;394.4339872283273;5025.346704928359;5107.336046313983;5215.973920130394;5514.876772442608;5549.334079910412;4295.420118284031;4617.6536759742985;5182;5276.525112739852;5385.461453854622;3937.744046636974;3944.5660977292473;3962.4650672159323;4092.3656833190903;4449.443819522559;3677.5770786359644;4323.767726198034;4381.342798580206;4503.639708823286;4524.411826462571;3827.502424684787;3887.300285948528;3987.6211759882763;4153.54311025787;4375.628071613901;3414.7097496712563;3657.689096426807;3886.3198940185353;4400.3498687992005;4445.711028783151;3841.2125514269505;4102.897427564335;4129.587041295877;4143.523494798076;4145.481814773153;4346.4349634547625;4875.390576177978;5237.476252374732;5244.79020839166;5266.420693723688;4327.8052487637815;4892.412910450733;5149.0474262261605;5156.793728250866;5180.611454140917;5686.943130568724;5703.77184912603;5711.9718450678865;5780.855478613026;5822.737976791054;3669.7614655047605;3822.0635944193573;4290.3135498320435;4381.342798580206;4525.524819893897;5357.892842143136;5395.433479484676;5696.77212911483;6075.9696201519155;6106.839700456899;4409.795102448776;4427.811975377723;4633.212353899845;4688.2733176357715;4697.577218050392;3706.7590606610484;4654.953450465452;4694.483606803249;4709.985609210108;4722.220833562493;4334.4798624165305;4434.578715022068;4908.94457691598;4937.087118600043;5121.463560931706;5155.350287907833;5474.7955449801775;5501.14732216507;5602.2717046783655;5742.167385729046;1439.7552416089081;1467.625755432358;1479.8150231221098;1624.018697189427;1639.1230691579947;881.3037700216757;1093.6718984304678;1177.4407156600716;1217.8173274008793;1230.6677197156719;4473.3960915276675;4736.644751643572;4852.393450818648;5185.22221666753;5343.664083979005;5214.270002199695;5436.3476382834315;5529.695866727368;5582.86042848932;6014.218151640259;3988.7606743594897;4492.483364413069;4597.7011494252765;5292.894142117136;5302.628815982845;2714.212878265281;2748.7938404619335;2756.379814541712;2904.724051215131;3122.252417872258;5724.856878577985;6158.414950579688;6209.9379006210265;6219.626822390671;6220.382700497903;5219.103397614361;5391.946080539223;5757.366689664133;6026.879462410728;6167.84580385485;5603.859903502445;6013.820394825554;6229.999999999909;6262.3111457739615;6269.373062693694;4860.6111511078325;5043.646944713835;5549.169923339206;5747.051736463437;6027.246594175728;4718.834840780556;5254.816081437131;5779.612892905757;5850.678212698343;5863.442972917566;4852.296417019583;5486.561075113901;5540.612157148961;6064.48451881595;6127.395856760948;4111.607923463095;4118.9702574356415;4361.454818147731;4441.22278601237;4634.212183928673;5019.67372120811;5824.271966004249;5854.707264636836;5925.614835035709;6002.819915402545;4713.62291016711;5357.919631205613;5495.42797181835;5660.716964151694;5709.486146246858;5061.392632884077;5105.6426050177615;5163.974180129076;5401.702906340189;5717.685527296038;4667.605380628425;4945.332380128637;5129.256257842667;5558.499735023867;5610.793679358794;5732.570057245806;5733.684647344269;5735.7418916148035;5744.013113181206;5786.855328616818;5012.548870601518;5541.8085111701175;5564;5592.105263157875;5593.692346920796 02:38:57 INFO - PROCESS | 1638 | |5;yui.html;688.1948120698781;708.4155571653387;767.5509826751351;771.6604693934669;832.4256263178406;15008.399664013426;16055.759163612554;16165.35338586458;16174.595635109128;16225.026498410103;14809.481668141618;14946;14992.250387480637;14997.700045999074;15023.023503472266;907.5552979040256;918.0452329577265;943.367943477868;949.1931857920747;950.097407462913;941.948192849393;952.4808979106369;953.1088432315772;955.1976339874504;955.2930831184908;995.4873240281261;1001.9849702254436;1002.939823610582;1003.282652903174;1004.6333088422746;992.6277645882794;996.2826764729383;996.6063404955038;996.8305388084007;998;727;737.5206116024573;738.9039424874732;739.6190961654752;740.4076738609091;16322.918385408117;16342.75485867713;16413.343466261336;16758.24587893548;17010.659786804317;11646.534138634444;11972.760544789142;14212.950285045703;14228.92885535576;14519.927400363036;11849.17055806094;11863.110266729944;12012.399380030964;12029.51881924722;12085.758284834343;3235.6205652673666;3651.7626354286886;3685.6130106338983;3720.2559488102215;3879.4568760373572;117245.4137729306;117873.41063294714;118601;122609.16086258713;123439;10859.13126949842;10955.616553420592;10996.450177491093;11116.666500005012;11399.544018239343;22821.657675134888;22979.540409191723;23347;23377.532449351256;23415;6628.502862285348;6703.12859328294;7680.193579674165;7793.337566306927;7823.608819558999;4244.533101358848;4277.465316835395;4314.158739045856;4319.9784001080425;4372.672049596293;3428.142964258935;3472.097254713742;3496.9825150874085;3513.9297214055578;3532.2405682778326;540.3380858448369;633.3444884544474;640.8397900524869;641.252940324526;643.0932385336746;639.7409049334934;642.6537937863596;646.1890327638783;671.9563228390139;685.5509641185032;9311.882574091152;9427.670031548863;9686.612535498502;9866.161376283097;9922.107010369102;563.3155715805316;631.2046821005566;637.5760119520467;641.6695401868042;646.0535315762337;4356.99578194028;4684.790332278242;4695.600873925686;4739.573438390561;4812.975935120302;105383.4730826341;106544.40183397256;107115.46442267895;109582.90417095886;110808.33787493194;70014.64992675107;75165.6241718788;75515;81415;83372.58313708393;80600;81330;83285;83387.91530211671;84362.73455898167;8902.59938302791;9148.725538233864;9196.494192819458;9299.582108651664;9310.068993100636;53444.73277633587;53677;53866.73066634642;54920.72539637276;55816.720916395156;67889.32110679027;68106.31893681;69400;70383;70867.22831929244;10348.948255258976;10479.895201048197;10762.461876905967;10932.344059356465;11095.22333436684;9860.556274967787;11988.580399685969;12008.759824803281;12113.81829272544;12426.633070362072;9045.095490450902;10620.20348473883;11578.189526733047;11592.420378981185;12238.816417753562;11275.097992160772;11648.359340236368;11775.52897884075;11843;12118.971366332138;9463.952680236554;11010.669679909615;11462.656120316404;11490.655280341603;11514.539418423505;7889.487183333065;7971.681132754625;8019.117897031205;8201.26188643025;8226.383021273308;9048.728538143867;9129.817403652023;9227.677031303872;9636.865956431955;9737.367071140354 02:38:57 INFO - PROCESS | 1638 | __end_tp_report 02:38:57 INFO - PROCESS | 1638 | __start_cc_report 02:38:57 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,414 02:38:57 INFO - PROCESS | 1638 | __end_cc_report 02:38:57 INFO - PROCESS | 1638 | __startTimestamp1491903537647__endTimestamp 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | ------- Summary: start ------- 02:38:57 INFO - PROCESS | 1638 | Number of tests: 6 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6022.95 Median:6268.15 stddev:662.51 (10.6%) stddev-sans-first:633.81 02:38:57 INFO - PROCESS | 1638 | Values: 3480.6 3539.7 3540.7 3551.4 3558.7 5474.0 5745.5 6145.4 6157.2 6170.1 6109.7 6194.1 6205.8 6212.8 6213.5 5453.5 5549.3 5569.5 5570.9 5646.6 5616.8 5623.7 5634.2 5641.3 5671.9 5388.1 5401.0 5515.9 5623.7 5636.6 5615.8 5615.9 5626.3 5653.0 5669.2 5432.2 5513.6 5528.8 5529.8 5548.9 5340.2 5916.9 5926.1 6108.6 6236.7 6378.2 6419.9 6426.8 6498.1 6530.3 6579.0 6580.2 6583.8 6588.5 6594.7 6209.6 6230.9 6250.2 6257.4 6282.9 5384.7 5475.6 6145.3 6617.8 6692.1 6454.0 6458.7 6462.2 6473.0 6490.8 6505.6 6611.4 6613.0 6617.0 6622.7 6265.9 6301.0 6319.2 6393.6 6510.3 6293.7 6364.6 6405.7 6438.1 6501.9 5187.5 5258.3 5261.3 5264.8 5278.2 4652.6 4678.3 4678.5 4696.5 4743.9 6049.8 6098.9 6298.2 6298.4 6319.1 6465.3 6465.3 6518.6 6521.6 6550.0 5322.1 5372.5 5891.5 6185.7 6282.5 5611.5 5794.9 5803.0 5804.8 5809.0 5602.8 6565.5 6631.4 6741.4 6829.3 6509.9 6531.6 6567.0 6575.4 6597.6 6787.7 6801.0 6818.7 6823.1 6824.0 5358.0 6288.2 6318.4 6376.7 6452.9 6471.9 6552.9 6555.5 6579.9 6599.4 5515.8 6389.7 6390.0 6412.0 6419.0 5697.7 6268.2 6311.8 6416.6 6432.7 6000.5 6227.8 6362.6 6376.3 6387.1 6249.5 6368.7 6388.8 6415.2 6457.0 6214.8 6287.4 6290.0 6310.5 6341.4 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:55026.82 Median:29711.41 stddev:115743.39 (389.6%) stddev-sans-first:115996.29 02:38:57 INFO - PROCESS | 1638 | Values: 732.9 799.1 817.8 950.7 958.6 38099.4 38875.0 40571.2 40583.0 40622.8 31323.0 39106.2 39108.6 39236.2 39246.0 7910.6 8016.4 10425.6 10721.5 10764.7 10473.8 10521.6 10561.8 10595.0 10610.5 10578.6 10600.7 10606.6 10607.1 10609.5 10561.3 10564.5 10591.1 10599.6 10623.8 10299.7 10362.7 10371.2 10373.8 10397.0 34202.7 34322.1 34354.0 34355.8 34527.0 3154.9 3662.3 3763.5 3764.4 3774.9 24854.3 25424.9 26358.5 26428.7 26558.7 2832.3 2889.6 3003.9 3018.4 3018.6 346721.0 351249.0 352277.0 352382.0 352858.2 43592.6 44227.0 44387.1 44397.0 44568.6 46234.3 46267.0 46295.5 46437.5 46577.1 11282.4 13926.0 14056.9 14057.4 14076.2 10043.7 11984.9 12190.0 12192.8 12203.3 10225.5 11434.1 11822.6 11941.8 11951.9 4942.9 5674.1 5694.4 5718.6 5728.1 936.0 952.2 960.6 982.8 986.2 27344.6 27965.7 28057.2 28199.9 28652.7 898.9 996.9 1045.2 1045.9 1079.8 11168.4 11410.1 14474.0 14607.9 14782.0 492797.0 495070.0 495382.0 495401.0 496716.0 46228.5 46573.5 46642.0 48434.8 49953.8 1366.1 1426.3 1436.3 1436.7 1441.1 25361.6 26439.3 26816.0 27123.2 27340.5 40350.4 40524.0 40556.8 40685.0 40737.0 443700.0 444145.0 445397.0 447751.0 448036.0 29557.4 29563.7 29710.4 29712.4 29923.0 37301.6 37611.8 37651.8 37855.0 37940.8 26317.2 27468.6 27653.0 27969.4 28143.0 20303.7 27150.9 27558.4 27702.9 27796.4 31369.7 31698.2 31759.0 31800.7 31905.8 21768.7 25055.2 26314.9 26812.3 27145.9 17530.8 21396.5 23059.8 23281.4 23954.8 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42902.89 Median:22086.95 stddev:124021.36 (561.5%) stddev-sans-first:124328.26 02:38:57 INFO - PROCESS | 1638 | Values: 423.3 433.1 436.0 446.5 447.1 19058.7 20170.9 21330.6 21409.7 21475.6 20509.9 21566.0 21874.6 21906.3 22141.4 6518.9 6703.4 6728.3 6778.7 6778.9 6403.9 6580.6 6603.7 6634.0 6665.4 6323.8 6583.3 6643.3 6658.4 6676.3 6560.9 6592.1 6603.1 6621.0 6653.8 6150.0 6255.6 6389.6 6420.9 6482.3 21607.2 23934.8 25368.2 26243.0 26728.3 2845.3 2967.2 2973.6 2974.6 2981.5 15617.3 15667.1 15954.6 16052.6 16253.6 1955.6 1961.4 1993.9 2000.5 2006.2 147084.0 151042.0 151727.2 176920.0 177179.0 15356.0 22036.0 22137.9 22207.6 22257.4 31850.8 32661.0 33000.2 33013.8 33143.5 10553.9 10573.2 10693.5 10722.2 10789.8 8412.0 8841.3 8956.4 9120.2 9123.6 9367.9 9578.2 9599.6 9699.0 9706.0 3229.5 4217.3 4285.4 4308.8 4323.1 886.5 896.3 899.1 905.3 932.6 20810.7 20956.5 21144.6 21250.5 21318.0 827.2 836.2 885.9 917.1 954.4 10161.8 10870.1 10901.9 10911.1 10932.5 732120.0 738824.0 738908.0 745322.0 758034.0 117705.4 131686.0 151449.0 155745.0 167197.0 1173.6 1192.0 1208.8 1218.7 1219.7 13500.8 19250.8 19725.0 19740.9 19846.7 59397.7 64615.0 66718.0 66860.7 66884.0 74718.0 92809.0 92968.9 94683.6 94805.1 10758.4 11556.0 11578.2 11764.2 11816.3 12775.7 12787.6 13022.2 13030.3 13231.6 9262.4 11129.3 11202.0 11427.4 11434.7 10469.0 10487.2 10597.7 10700.9 10789.7 11127.3 11241.7 11389.9 11436.1 11501.4 11615.8 11623.4 11912.6 11913.6 11916.2 10860.8 11165.9 11184.7 11211.8 11429.1 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6735.46 Median:2417.03 stddev:4267.83 (176.6%) stddev-sans-first:4261.02 02:38:57 INFO - PROCESS | 1638 | Values: 1407.3 1435.9 1491.5 1532.3 1536.4 9433.2 9682.2 10647.0 11227.2 11276.5 10669.5 11106.0 11156.2 11211.4 11231.4 2388.4 2426.2 2431.0 2435.0 2437.5 2369.0 2402.3 2413.7 2416.1 2418.0 2389.4 2396.0 2398.4 2412.6 2429.3 2364.2 2374.5 2376.7 2383.0 2386.2 2368.6 2375.9 2386.8 2387.4 2392.5 11133.0 11323.8 11327.8 11338.7 11396.9 5734.3 5828.2 5892.2 5918.4 5935.0 3505.4 3570.5 3592.7 3646.3 3660.1 1129.7 1147.0 1186.1 1190.6 1207.9 15334.5 15494.3 15516.7 15550.3 15555.9 4691.0 4883.3 4920.0 4955.6 5016.5 13053.7 13220.0 13223.7 13242.6 13285.3 5389.6 5819.2 6012.5 6026.2 6052.7 3995.5 4312.6 5241.9 5318.6 5337.6 3242.5 3933.7 4024.2 4048.6 4086.5 1129.1 1132.1 1132.9 1141.8 1145.9 926.8 927.9 937.8 940.2 961.2 6166.7 6378.3 6580.6 6727.0 6920.3 909.0 1000.8 1025.7 1028.6 1038.3 4233.2 4297.5 4362.4 4468.1 4484.4 12371.7 13301.5 13357.5 13960.6 13990.5 13472.0 13669.2 13775.7 13860.9 13890.5 10104.9 10110.2 10240.9 10269.9 10343.2 6542.8 6547.5 6605.0 6671.1 6720.6 12127.6 12259.5 12304.4 12312.2 12444.4 12525.8 12680.6 12700.8 12759.7 12762.9 7461.9 8352.7 8383.5 8386.5 8541.3 10222.4 10230.2 10317.1 10330.9 10342.9 8589.9 8609.4 8620.0 8631.3 8631.3 6948.5 8393.0 8403.5 8470.0 8525.0 7050.5 7804.9 7805.3 7847.2 7880.7 5597.4 5629.4 5659.5 5767.5 5819.4 5049.9 5891.5 5964.9 5979.6 6110.8 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4671.39 Median:5083.52 stddev:1352.46 (26.6%) stddev-sans-first:1316.67 02:38:57 INFO - PROCESS | 1638 | Values: 343.5 346.2 357.4 365.7 394.4 5025.3 5107.3 5216.0 5514.9 5549.3 4295.4 4617.7 5182.0 5276.5 5385.5 3937.7 3944.6 3962.5 4092.4 4449.4 3677.6 4323.8 4381.3 4503.6 4524.4 3827.5 3887.3 3987.6 4153.5 4375.6 3414.7 3657.7 3886.3 4400.3 4445.7 3841.2 4102.9 4129.6 4143.5 4145.5 4346.4 4875.4 5237.5 5244.8 5266.4 4327.8 4892.4 5149.0 5156.8 5180.6 5686.9 5703.8 5712.0 5780.9 5822.7 3669.8 3822.1 4290.3 4381.3 4525.5 5357.9 5395.4 5696.8 6076.0 6106.8 4409.8 4427.8 4633.2 4688.3 4697.6 3706.8 4655.0 4694.5 4710.0 4722.2 4334.5 4434.6 4908.9 4937.1 5121.5 5155.4 5474.8 5501.1 5602.3 5742.2 1439.8 1467.6 1479.8 1624.0 1639.1 881.3 1093.7 1177.4 1217.8 1230.7 4473.4 4736.6 4852.4 5185.2 5343.7 5214.3 5436.3 5529.7 5582.9 6014.2 3988.8 4492.5 4597.7 5292.9 5302.6 2714.2 2748.8 2756.4 2904.7 3122.3 5724.9 6158.4 6209.9 6219.6 6220.4 5219.1 5391.9 5757.4 6026.9 6167.8 5603.9 6013.8 6230.0 6262.3 6269.4 4860.6 5043.6 5549.2 5747.1 6027.2 4718.8 5254.8 5779.6 5850.7 5863.4 4852.3 5486.6 5540.6 6064.5 6127.4 4111.6 4119.0 4361.5 4441.2 4634.2 5019.7 5824.3 5854.7 5925.6 6002.8 4713.6 5357.9 5495.4 5660.7 5709.5 5061.4 5105.6 5164.0 5401.7 5717.7 4667.6 4945.3 5129.3 5558.5 5610.8 5732.6 5733.7 5735.7 5744.0 5786.9 5012.5 5541.8 5564.0 5592.1 5593.7 02:38:57 INFO - PROCESS | 1638 | 02:38:57 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20590.27 Median:29128.85 stddev:30886.76 (106.0%) stddev-sans-first:30937.26 02:38:57 INFO - PROCESS | 1638 | Values: 688.2 708.4 767.6 771.7 832.4 15008.4 16055.8 16165.4 16174.6 16225.0 14809.5 14946.0 14992.3 14997.7 15023.0 907.6 918.0 943.4 949.2 950.1 941.9 952.5 953.1 955.2 955.3 995.5 1002.0 1002.9 1003.3 1004.6 992.6 996.3 996.6 996.8 998.0 727.0 737.5 738.9 739.6 740.4 16322.9 16342.8 16413.3 16758.2 17010.7 11646.5 11972.8 14213.0 14228.9 14519.9 11849.2 11863.1 12012.4 12029.5 12085.8 3235.6 3651.8 3685.6 3720.3 3879.5 117245.4 117873.4 118601.0 122609.2 123439.0 10859.1 10955.6 10996.5 11116.7 11399.5 22821.7 22979.5 23347.0 23377.5 23415.0 6628.5 6703.1 7680.2 7793.3 7823.6 4244.5 4277.5 4314.2 4320.0 4372.7 3428.1 3472.1 3497.0 3513.9 3532.2 540.3 633.3 640.8 641.3 643.1 639.7 642.7 646.2 672.0 685.6 9311.9 9427.7 9686.6 9866.2 9922.1 563.3 631.2 637.6 641.7 646.1 4357.0 4684.8 4695.6 4739.6 4813.0 105383.5 106544.4 107115.5 109582.9 110808.3 70014.6 75165.6 75515.0 81415.0 83372.6 80600.0 81330.0 83285.0 83387.9 84362.7 8902.6 9148.7 9196.5 9299.6 9310.1 53444.7 53677.0 53866.7 54920.7 55816.7 67889.3 68106.3 69400.0 70383.0 70867.2 10348.9 10479.9 10762.5 10932.3 11095.2 9860.6 11988.6 12008.8 12113.8 12426.6 9045.1 10620.2 11578.2 11592.4 12238.8 11275.1 11648.4 11775.5 11843.0 12119.0 9464.0 11010.7 11462.7 11490.7 11514.5 7889.5 7971.7 8019.1 8201.3 8226.4 9048.7 9129.8 9227.7 9636.9 9737.4 02:38:57 INFO - PROCESS | 1638 | -------- Summary: end -------- 02:38:57 INFO - PROCESS | 1638 | 02:38:58 INFO - TEST-INFO | 1638: exit 0 02:38:58 INFO - TEST-OK | dromaeo_css | took 1117336ms 02:38:58 INFO - TEST-START | kraken 02:38:58 INFO - operating with platform_type : mac_ 02:38:58 INFO - Initialising browser for kraken test... 02:38:58 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpcupNZn/profile http://localhost:49241/getInfo.html) 02:39:01 INFO - PROCESS | 1650 | 2017-04-11 02:39:01.347 plugin-container[1652:10224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 02:39:01 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:39:01 INFO - PROCESS | 1650 | 2017-04-11 02:39:01.348 plugin-container[1652:10224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 02:39:01 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:39:01 INFO - PROCESS | 1650 | 2017-04-11 02:39:01.348 plugin-container[1652:10224] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:39:01 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:39:01 INFO - PROCESS | 1650 | colorDepth:24 02:39:01 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:39:01 INFO - PROCESS | 1650 | __metrics 02:39:01 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:39:02 INFO - TEST-INFO | 1650: exit 0 02:39:02 INFO - Browser initialized. 02:39:02 INFO - Running cycle 1/1 for kraken test... 02:39:02 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpcupNZn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:39:04 INFO - PROCESS | 1653 | 2017-04-11 02:39:04.766 plugin-container[1654:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 02:39:04 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:39:04 INFO - PROCESS | 1653 | 2017-04-11 02:39:04.767 plugin-container[1654:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b2b, name = 'com.apple.CFPasteboardClient' 02:39:04 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:39:04 INFO - PROCESS | 1653 | 2017-04-11 02:39:04.767 plugin-container[1654:10348] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:39:14 INFO - PROCESS | 1653 | RSS: Main: 220622848 02:39:14 INFO - PROCESS | 1653 | 02:39:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:39:18 INFO - PROCESS | 1653 | RSS: Main: 235347968 02:39:18 INFO - PROCESS | 1653 | 02:39:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:39:21 INFO - PROCESS | 1653 | RSS: Main: 243396608 02:39:21 INFO - PROCESS | 1653 | 02:39:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:39:25 INFO - PROCESS | 1653 | RSS: Main: 246964224 02:39:25 INFO - PROCESS | 1653 | 02:39:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:39:27 INFO - PROCESS | 1653 | RSS: Main: 248664064 02:39:27 INFO - PROCESS | 1653 | 02:39:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:39:30 INFO - PROCESS | 1653 | RSS: Main: 248487936 02:39:30 INFO - PROCESS | 1653 | 02:39:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:39:33 INFO - PROCESS | 1653 | RSS: Main: 252854272 02:39:33 INFO - PROCESS | 1653 | 02:39:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:39:37 INFO - PROCESS | 1653 | RSS: Main: 253390848 02:39:37 INFO - PROCESS | 1653 | 02:39:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:39:41 INFO - PROCESS | 1653 | RSS: Main: 252547072 02:39:41 INFO - PROCESS | 1653 | 02:39:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:39:44 INFO - PROCESS | 1653 | RSS: Main: 251846656 02:39:44 INFO - PROCESS | 1653 | 02:39:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:39:46 INFO - PROCESS | 1653 | RSS: Main: 251928576 02:39:46 INFO - PROCESS | 1653 | 02:39:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:39:49 INFO - PROCESS | 1653 | RSS: Main: 251940864 02:39:49 INFO - PROCESS | 1653 | 02:39:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:39:53 INFO - PROCESS | 1653 | RSS: Main: 252370944 02:39:53 INFO - PROCESS | 1653 | 02:39:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:39:56 INFO - PROCESS | 1653 | RSS: Main: 252096512 02:39:56 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:39:58 INFO - PROCESS | 1653 | RSS: Main: 254021632 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | __start_tp_report 02:39:58 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:39:58 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:39:58 INFO - PROCESS | 1653 | |i|pagename|runs| 02:39:58 INFO - PROCESS | 1653 | |0;ai-astar;108;122;119;120;120;120;120;120;119;119 02:39:58 INFO - PROCESS | 1653 | |1;audio-beat-detection;138;110;114;139;117;147;136;114;130;128 02:39:58 INFO - PROCESS | 1653 | |2;audio-dft;194;199;194;199;199;198;198;192;199;200 02:39:58 INFO - PROCESS | 1653 | |3;audio-fft;83;59;63;90;63;61;62;63;75;60 02:39:58 INFO - PROCESS | 1653 | |4;audio-oscillator;93;71;86;72;72;69;81;72;72;83 02:39:58 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;90;88;89;89;88;89;89;130;137 02:39:58 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;126;96;112;130;152;147;95;95;96 02:39:58 INFO - PROCESS | 1653 | |7;imaging-desaturate;149;146;91;93;89;89;138;141;91;91 02:39:58 INFO - PROCESS | 1653 | |8;json-parse-financial;65;64;65;65;65;67;68;76;78;81 02:39:58 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;56;80;52;62;103;93;87;85;85;50 02:39:58 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;73;85;73;98;105;75;106;76;108;105 02:39:58 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;155;141;133;143;152;150;147;148;210;167 02:39:58 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;166;164;167;163;168;172;204;169;167;164 02:39:58 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;66;65;76;82;88;87;61;59;83 02:39:58 INFO - PROCESS | 1653 | __end_tp_report 02:39:58 INFO - PROCESS | 1653 | __start_cc_report 02:39:58 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1006 02:39:58 INFO - PROCESS | 1653 | __end_cc_report 02:39:58 INFO - PROCESS | 1653 | __startTimestamp1491903598966__endTimestamp 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:39:58 INFO - PROCESS | 1653 | Number of tests: 14 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:118.70 Median:120.00 stddev:3.86 (3.2%) stddev-sans-first:0.93 02:39:58 INFO - PROCESS | 1653 | Values: 108.0 122.0 119.0 120.0 120.0 120.0 120.0 120.0 119.0 119.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:133.00 stddev:12.83 (9.6%) stddev-sans-first:13.01 02:39:58 INFO - PROCESS | 1653 | Values: 138.0 110.0 114.0 139.0 117.0 147.0 136.0 114.0 130.0 128.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:197.20 Median:199.00 stddev:2.78 (1.4%) stddev-sans-first:2.70 02:39:58 INFO - PROCESS | 1653 | Values: 194.0 199.0 194.0 199.0 199.0 198.0 198.0 192.0 199.0 200.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:67.90 Median:63.00 stddev:10.87 (17.2%) stddev-sans-first:10.06 02:39:58 INFO - PROCESS | 1653 | Values: 83.0 59.0 63.0 90.0 63.0 61.0 62.0 63.0 75.0 60.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:76.50 stddev:8.09 (10.6%) stddev-sans-first:6.20 02:39:58 INFO - PROCESS | 1653 | Values: 93.0 71.0 86.0 72.0 72.0 69.0 81.0 72.0 72.0 83.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:89.00 stddev:18.90 (21.2%) stddev-sans-first:19.77 02:39:58 INFO - PROCESS | 1653 | Values: 89.0 90.0 88.0 89.0 89.0 88.0 89.0 89.0 130.0 137.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:95.00 stddev:22.77 (24.0%) stddev-sans-first:23.04 02:39:58 INFO - PROCESS | 1653 | Values: 95.0 126.0 96.0 112.0 130.0 152.0 147.0 95.0 95.0 96.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:111.80 Median:90.00 stddev:27.45 (30.5%) stddev-sans-first:25.61 02:39:58 INFO - PROCESS | 1653 | Values: 149.0 146.0 91.0 93.0 89.0 89.0 138.0 141.0 91.0 91.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:67.50 stddev:6.38 (9.5%) stddev-sans-first:6.57 02:39:58 INFO - PROCESS | 1653 | Values: 65.0 64.0 65.0 65.0 65.0 67.0 68.0 76.0 78.0 81.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:75.30 Median:82.50 stddev:18.74 (22.7%) stddev-sans-first:18.53 02:39:58 INFO - PROCESS | 1653 | Values: 56.0 80.0 52.0 62.0 103.0 93.0 87.0 85.0 85.0 50.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:74.00 stddev:15.33 (20.7%) stddev-sans-first:14.92 02:39:58 INFO - PROCESS | 1653 | Values: 73.0 85.0 73.0 98.0 105.0 75.0 106.0 76.0 108.0 105.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:151.00 stddev:21.44 (14.2%) stddev-sans-first:22.74 02:39:58 INFO - PROCESS | 1653 | Values: 155.0 141.0 133.0 143.0 152.0 150.0 147.0 148.0 210.0 167.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:167.50 stddev:12.10 (7.2%) stddev-sans-first:12.73 02:39:58 INFO - PROCESS | 1653 | Values: 166.0 164.0 167.0 163.0 168.0 172.0 204.0 169.0 167.0 164.0 02:39:58 INFO - PROCESS | 1653 | 02:39:58 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:79.00 stddev:10.99 (13.9%) stddev-sans-first:11.47 02:39:58 INFO - PROCESS | 1653 | Values: 68.0 66.0 65.0 76.0 82.0 88.0 87.0 61.0 59.0 83.0 02:39:58 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:39:58 INFO - PROCESS | 1653 | 02:39:59 INFO - TEST-INFO | 1653: exit 0 02:39:59 INFO - TEST-OK | kraken | took 61366ms 02:39:59 INFO - SUITE-END | took 1178s 02:40:00 INFO - Completed test suite (00:19:38) 02:40:00 INFO - javascript benchmark 02:40:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7618.600020808017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.5997310309313, 3539.6991255743246, 3540.6636369544885, 3551.431770916653, 3558.7330950178743, 5473.972630136849, 5745.454181852722, 6145.354737752533, 6157.230346206724, 6170.136180934662, 6109.663968481732, 6194.070889366597, 6205.782797602084, 6212.78255261066, 6213.502919766419, 5453.481919217678, 5549.278593782803, 5569.470900264469, 5570.944290557103, 5646.604737668365, 5616.803411880585, 5623.690697011662, 5634.211210430543, 5641.266635337401, 5671.943280567203, 5388.084025715618, 5400.973814975162, 5515.889682206353, 5623.662580245178, 5636.633618814764, 5615.831525054255, 5615.943840561624, 5626.2967129108865, 5652.971735141339, 5669.206311116447, 5432.239486471897, 5513.5864810139, 5528.834134975917, 5529.834104976857, 5548.91676624851, 5340.172273297645, 5916.940830591682, 5926.1110833374905, 6108.602940808833, 6236.719347629368, 6378.234611846562, 6419.935800641981, 6426.778912006704, 6498.122753428274, 6530.346965303432, 6579.0, 6580.243272023683, 6583.769568065095, 6588.505862060317, 6594.670266486656, 6209.565330426872, 6230.937690623035, 6250.1874756281395, 6257.374262573751, 6282.905756413657, 5384.703841288767, 5475.589330800126, 6145.324014358417, 6617.8014659560295, 6692.096566963495, 6453.967730161319, 6458.709358078898, 6462.159919210472, 6473.0, 6490.8052758417325, 6505.60966342021, 6611.438027767694, 6612.966935165389, 6617.0, 6622.701978410983, 6265.8746825063245, 6300.9684951574945, 6319.178506794087, 6393.552451328363, 6510.25132109804, 6293.748250070037, 6364.554481186273, 6405.654812489336, 6438.130852334969, 6501.934980650228, 5187.455317191716, 5258.342707161605, 5261.342332208474, 5264.842054738364, 5278.234655974862, 4652.62778977681, 4678.3216433616935, 4678.461976872635, 4696.530346965276, 4743.905121897543, 6049.78825741097, 6098.939010609926, 6298.212723409574, 6298.370162983665, 6319.05214217871, 6465.288818229991, 6465.288818229991, 6518.5762925410645, 6521.576097553644, 6550.017497375432, 5322.148456246982, 5372.495701203669, 5891.49922256613, 6185.721642526097, 6282.528810339243, 5611.466910643482, 5794.8551286217335, 5802.970985145047, 5804.79683211094, 5809.0, 5602.775888964396, 6565.540412171103, 6631.403173714292, 6741.426978706865, 6829.282925292773, 6509.8698026040165, 6531.640759758259, 6566.967165164144, 6575.375339342755, 6597.637129957899, 6787.694553744995, 6801.0, 6818.727250909908, 6823.147106611674, 6823.965880170567, 5358.0, 6288.182536270256, 6318.368163183645, 6376.74493020274, 6452.903206452001, 6471.870562588816, 6552.934470655233, 6555.475561954937, 6579.934200657932, 6599.373059559334, 5515.841673248663, 6389.744410223539, 6390.009548519987, 6411.967940160456, 6419.037144428373, 5697.715114244188, 6268.153799237227, 6311.842203944938, 6416.550841441241, 6432.678366081584, 6000.489958353589, 6227.813165605039, 6362.618242905441, 6376.330485298977, 6387.073874288295, 6249.531285153723, 6368.713407896748, 6388.776392826228, 6415.1981002374705, 6457.0, 6214.8135555933395, 6287.371262873676, 6289.968550157403, 6310.49516038725, 6341.397567231105], "value": 5983.18314766179, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.9157146928105, 799.0850476204746, 817.824167803922, 950.743299309187, 958.6117622362937, 38099.4285085724, 38875.02812429687, 40571.188576228495, 40583.0, 40622.7968860156, 31323.0, 39106.21787564247, 39108.60891391085, 39236.21527569447, 39246.0, 7910.644021019039, 8016.438849280553, 10425.635102771404, 10721.463926803628, 10764.73088172798, 10473.790524189511, 10521.631742889, 10561.788764224711, 10595.0, 10610.469476526143, 10578.576856925714, 10600.734981625445, 10606.575736970512, 10607.098396636295, 10609.469526523642, 10561.260711750181, 10564.524596393143, 10591.099756520705, 10599.629012984547, 10623.84064239037, 10299.742506437324, 10362.740931476697, 10371.222158338154, 10373.84439233412, 10397.012283833024, 34202.657973420195, 34322.14194645129, 34354.0, 34355.82822085899, 34526.96419107431, 3154.8895788647287, 3662.34077865984, 3763.5295588051495, 3764.3976963685955, 3774.924501509982, 24854.254372368858, 25424.872875635505, 26358.472830543473, 26428.73571264282, 26558.672066396604, 2832.2636114610145, 2889.552119421493, 3003.8798448062053, 3018.351054523288, 3018.622672165979, 346721.0, 351249.0, 352277.0, 352382.0, 352858.2357088224, 43592.564074359485, 44227.0, 44387.11225775466, 44397.0, 44568.55431445709, 46234.30648540275, 46267.0, 46295.5370446298, 46437.53562464332, 46577.06845863064, 11282.435878206057, 13925.95555333354, 14056.929715351562, 14057.43770249199, 14076.225807580477, 10043.69868903934, 11984.940075299568, 12190.0, 12192.81710774339, 12203.328816914978, 10225.539850706737, 11434.142439317084, 11822.586209482799, 11941.76116477683, 11951.880481195076, 4942.9258561121615, 5674.120511320752, 5694.402087780806, 5718.628289161191, 5728.05487094633, 935.9578818953163, 952.1716106986827, 960.6157536985262, 982.8181786369545, 986.1765425869336, 27344.58983115255, 27965.72034279631, 28057.158285251477, 28199.91913786988, 28652.71347286542, 898.910108989109, 996.9482196282991, 1045.242199405425, 1045.900639439252, 1079.83262594298, 11168.441577921072, 11410.144239182095, 14473.986820922435, 14607.92696036513, 14782.0, 492797.0, 495070.0, 495382.0, 495401.0, 496716.0, 46228.5377146231, 46573.53426465692, 46642.0, 48434.78913027202, 49953.75023124861, 1366.1393322206945, 1426.2868565717142, 1436.3033928544648, 1436.7485690004207, 1441.076163321977, 25361.605111719033, 26439.33901652474, 26816.025434043026, 27123.186304410898, 27340.453190935674, 40350.39474407945, 40523.98690032773, 40556.79721601373, 40684.982875427166, 40737.0, 443700.0, 444145.0, 445397.0, 447751.0, 448036.0, 29557.408851823275, 29563.704362956094, 29710.405791884474, 29712.405751885275, 29923.0, 37301.62698372982, 37611.81194094012, 37651.81174094112, 37855.0, 37940.810295948344, 26317.21048368552, 27468.587971180048, 27653.0, 27969.44061118807, 28143.014994475096, 20303.69544456805, 27150.864245678644, 27558.448831022866, 27702.861485692443, 27796.44407111887, 31369.686303136677, 31698.207544811565, 31759.0, 31800.681993179773, 31905.8404707975, 21768.67346989828, 25055.248342549752, 26314.86842565775, 26812.32969175708, 27145.86427067852, 17530.824691752918, 21396.465088372915, 23059.769402305763, 23281.417964551023, 23954.760452395254], "value": 17708.258172690556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.28042328042335, 433.1186036415894, 435.9825606975719, 446.510886194541, 447.05894092934375, 19058.714119288186, 20170.89914550427, 21330.57338853222, 21409.67885481719, 21475.57048859022, 20509.897450512726, 21566.02952867121, 21874.562508749856, 21906.342809715694, 22141.446463838412, 6518.902216466769, 6703.363180497845, 6728.293529179439, 6778.694958726845, 6778.864422711567, 6403.903941440882, 6580.638064906429, 6603.70283337251, 6633.966830165842, 6665.366790154952, 6323.841903952392, 6583.341665833426, 6643.302453242412, 6658.434033107192, 6676.332366763309, 6560.901586476207, 6592.143021407234, 6603.108580341641, 6621.006848972645, 6653.800385988404, 6149.954507733652, 6255.624662520263, 6389.584676995981, 6420.903686444707, 6482.286948435668, 21607.243746468797, 23934.7606523936, 25368.23895283126, 26243.0, 26728.331791705168, 2845.3455705187716, 2967.213688372583, 2973.6431628204537, 2974.628171478565, 2981.4633365994114, 15617.297221625055, 15667.059976401339, 15954.601134971603, 16052.59868503285, 16253.593660158473, 1955.5697746495748, 1961.3723608445302, 1993.8903360315178, 2000.5290820426549, 2006.1574138861567, 147084.0, 151042.0, 151727.24136379358, 176920.0, 177179.0, 15356.0, 22036.0, 22137.89310534467, 22207.55584888293, 22257.443563910707, 31850.840745796588, 32661.020169394957, 33000.174995624824, 33013.83493082519, 33143.502847457305, 10553.894461055292, 10573.207009474167, 10693.465326733787, 10722.249442538947, 10789.784204315869, 8411.957940210383, 8841.292696584258, 8956.373053886166, 9120.179183873483, 9123.589438475285, 9367.859482107773, 9578.233741300679, 9599.568019439143, 9698.951505242429, 9706.029397060238, 3229.531717900891, 4217.34631132175, 4285.357196420562, 4308.849190278325, 4323.135372925428, 886.5389997201548, 896.2695403246464, 899.0874262623443, 905.330055758747, 932.6362718539775, 20810.687839682418, 20956.476088097614, 21144.6255993363, 21250.468738281357, 21318.0, 827.2430725885868, 836.180543067797, 885.8804061451622, 917.060013486182, 954.4178051388648, 10161.847572286422, 10870.130389568816, 10901.890981090246, 10911.127109831194, 10932.508037138348, 732120.0, 738824.0, 738908.0, 745322.0, 758034.0, 117705.41147294208, 131686.0, 151449.0, 155745.0, 167197.0, 1173.5892437646926, 1191.9821202681967, 1208.7763763703742, 1218.737971336167, 1219.6828824505515, 13500.797488037884, 19250.8074919249, 19725.0, 19740.90129549343, 19846.702299465807, 59397.703011484664, 64615.0, 66717.99923001062, 66860.6656966712, 66884.0, 74718.0, 92809.0, 92968.87107014067, 94683.57974630248, 94805.05194948238, 10758.40828754426, 11556.0, 11578.247413918069, 11764.176552634355, 11816.291022538675, 12775.744485110059, 12787.552435665079, 13022.218666880017, 13030.283334416697, 13231.603051908458, 9262.44425334482, 11129.332240065622, 11201.991820736173, 11427.371494567878, 11434.714132146762, 10469.0, 10487.213458990758, 10597.682069537927, 10700.946495267473, 10789.73025674333, 11127.332360058099, 11241.71895702614, 11389.943050285026, 11436.1422893285, 11501.367424791382, 11615.825762613737, 11623.360715160747, 11912.642620721392, 11913.583024594098, 11916.16586838913, 10860.797047164979, 11165.944170279097, 11184.664460066211, 11211.831822522505, 11429.142814288794], "value": 11481.82468843842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.3455843032987, 1435.9497417590387, 1491.522712731925, 1532.2874863188622, 1536.3624096000158, 9433.198178154847, 9682.225421966243, 10647.0, 11227.157963152766, 11276.492557834896, 10669.466526673674, 11106.0, 11156.16328775343, 11211.439428028607, 11231.438428078603, 2388.3909603051197, 2426.2478631624163, 2431.0032886516533, 2434.9513009739794, 2437.5003124359464, 2368.95262094758, 2402.267308470916, 2413.698287714036, 2416.093964763214, 2417.9758202418016, 2389.438481956739, 2395.9520809583796, 2398.352444839892, 2412.589859723851, 2429.2712186344115, 2364.207990323244, 2374.5013547155118, 2376.6791483149814, 2383.0, 2386.200622791367, 2368.585497537941, 2375.869327187004, 2386.844855084414, 2387.403149212697, 2392.485615592656, 11132.998030177323, 11323.773524529546, 11327.830082548768, 11338.659840204808, 11396.943015284953, 5734.340550836666, 5828.242328497268, 5892.234009578728, 5918.3785702501045, 5934.97032514839, 3505.386557352453, 3570.4644303354444, 3592.6946209572216, 3646.32542979547, 3660.103274697716, 1129.7458071933831, 1147.0, 1186.1400484648648, 1190.6190019193862, 1207.885250901163, 15334.539963801104, 15494.30275637599, 15516.689666206725, 15550.300236489385, 15555.922220388939, 4690.95309046912, 4883.316335713003, 4920.0, 4955.578775804097, 5016.473270306638, 13053.673658158623, 13220.0, 13223.73552528944, 13242.602721918358, 13285.3357332133, 5389.595780316414, 5819.243498345188, 6012.518998480198, 6026.246719160105, 6052.6671033092725, 3995.5405128410025, 4312.633426158812, 5241.9213711794355, 5318.601104917147, 5337.599680024014, 3242.5460435539044, 3933.7246392752804, 4024.1549274652652, 4048.595140485928, 4086.5096188457574, 1129.0910816792561, 1132.0773569540797, 1132.9206955513034, 1141.7668917569172, 1145.873953865074, 926.7822061815468, 927.8561822917459, 937.7843096087938, 940.1867384712175, 961.1733908838394, 6166.660833654102, 6378.266499352634, 6580.5722628029025, 6727.0, 6920.30796920314, 908.981820363589, 1000.7798284377425, 1025.6512785652915, 1028.6142696488816, 1038.2732087538752, 4233.216854881795, 4297.52727200014, 4362.411074504965, 4468.128714900628, 4484.417025786692, 12371.6907077322, 13301.534446294527, 13357.46570137203, 13960.58118256454, 13990.510332138327, 13472.0, 13669.179849209078, 13775.724485510234, 13860.89112870968, 13890.472048074704, 10104.949475252577, 10110.191184705205, 10240.897591023995, 10269.948650256702, 10343.224258180517, 6542.803715888531, 6547.541672082908, 6605.0, 6671.114076050592, 6720.596764193969, 12127.636170914886, 12259.509619615472, 12304.384780761106, 12312.199707019015, 12444.377781111089, 12525.812112818132, 12680.619581412573, 12700.809487857874, 12759.74480510366, 12762.872371276168, 7461.925380746123, 8352.707655232038, 8383.455075420323, 8386.538740369338, 8541.316694664536, 10222.386656800616, 10230.232732545237, 10317.071463568314, 10330.948345258226, 10342.948285258826, 8589.8711519326, 8609.440386374865, 8619.956900215459, 8631.26634236097, 8631.26634236097, 6948.478864085315, 8393.0, 8403.537805420761, 8469.957650211709, 8524.957375213333, 7050.541714788523, 7804.882926756217, 7805.297523222937, 7847.176046515034, 7880.724174653859, 5597.384287728428, 5629.437056294338, 5659.54723622101, 5767.480926716616, 5819.447152520504, 5049.8737531562, 5891.49922256613, 5964.940350596438, 5979.611325263844, 6110.847228819315], "value": 5132.621473168816, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4556228378021, 346.19164251472813, 357.4316836230393, 365.73666959788943, 394.4339872283273, 5025.346704928359, 5107.336046313983, 5215.973920130394, 5514.876772442608, 5549.334079910412, 4295.420118284031, 4617.6536759742985, 5182.0, 5276.525112739852, 5385.461453854622, 3937.744046636974, 3944.5660977292473, 3962.4650672159323, 4092.3656833190903, 4449.443819522559, 3677.5770786359644, 4323.767726198034, 4381.342798580206, 4503.639708823286, 4524.411826462571, 3827.502424684787, 3887.300285948528, 3987.6211759882763, 4153.54311025787, 4375.628071613901, 3414.7097496712563, 3657.689096426807, 3886.3198940185353, 4400.3498687992005, 4445.711028783151, 3841.2125514269505, 4102.897427564335, 4129.587041295877, 4143.523494798076, 4145.481814773153, 4346.4349634547625, 4875.390576177978, 5237.476252374732, 5244.79020839166, 5266.420693723688, 4327.8052487637815, 4892.412910450733, 5149.0474262261605, 5156.793728250866, 5180.611454140917, 5686.943130568724, 5703.77184912603, 5711.9718450678865, 5780.855478613026, 5822.737976791054, 3669.7614655047605, 3822.0635944193573, 4290.3135498320435, 4381.342798580206, 4525.524819893897, 5357.892842143136, 5395.433479484676, 5696.77212911483, 6075.9696201519155, 6106.839700456899, 4409.795102448776, 4427.811975377723, 4633.212353899845, 4688.2733176357715, 4697.577218050392, 3706.7590606610484, 4654.953450465452, 4694.483606803249, 4709.985609210108, 4722.220833562493, 4334.4798624165305, 4434.578715022068, 4908.94457691598, 4937.087118600043, 5121.463560931706, 5155.350287907833, 5474.7955449801775, 5501.14732216507, 5602.2717046783655, 5742.167385729046, 1439.7552416089081, 1467.625755432358, 1479.8150231221098, 1624.018697189427, 1639.1230691579947, 881.3037700216757, 1093.6718984304678, 1177.4407156600716, 1217.8173274008793, 1230.6677197156719, 4473.3960915276675, 4736.644751643572, 4852.393450818648, 5185.22221666753, 5343.664083979005, 5214.270002199695, 5436.3476382834315, 5529.695866727368, 5582.86042848932, 6014.218151640259, 3988.7606743594897, 4492.483364413069, 4597.7011494252765, 5292.894142117136, 5302.628815982845, 2714.212878265281, 2748.7938404619335, 2756.379814541712, 2904.724051215131, 3122.252417872258, 5724.856878577985, 6158.414950579688, 6209.9379006210265, 6219.626822390671, 6220.382700497903, 5219.103397614361, 5391.946080539223, 5757.366689664133, 6026.879462410728, 6167.84580385485, 5603.859903502445, 6013.820394825554, 6229.999999999909, 6262.3111457739615, 6269.373062693694, 4860.6111511078325, 5043.646944713835, 5549.169923339206, 5747.051736463437, 6027.246594175728, 4718.834840780556, 5254.816081437131, 5779.612892905757, 5850.678212698343, 5863.442972917566, 4852.296417019583, 5486.561075113901, 5540.612157148961, 6064.48451881595, 6127.395856760948, 4111.607923463095, 4118.9702574356415, 4361.454818147731, 4441.22278601237, 4634.212183928673, 5019.67372120811, 5824.271966004249, 5854.707264636836, 5925.614835035709, 6002.819915402545, 4713.62291016711, 5357.919631205613, 5495.42797181835, 5660.716964151694, 5709.486146246858, 5061.392632884077, 5105.6426050177615, 5163.974180129076, 5401.702906340189, 5717.685527296038, 4667.605380628425, 4945.332380128637, 5129.256257842667, 5558.499735023867, 5610.793679358794, 5732.570057245806, 5733.684647344269, 5735.7418916148035, 5744.013113181206, 5786.855328616818, 5012.548870601518, 5541.8085111701175, 5564.0, 5592.105263157875, 5593.692346920796], "value": 4271.7064975061985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.1948120698781, 708.4155571653387, 767.5509826751351, 771.6604693934669, 832.4256263178406, 15008.399664013426, 16055.759163612554, 16165.35338586458, 16174.595635109128, 16225.026498410103, 14809.481668141618, 14946.0, 14992.250387480637, 14997.700045999074, 15023.023503472266, 907.5552979040256, 918.0452329577265, 943.367943477868, 949.1931857920747, 950.097407462913, 941.948192849393, 952.4808979106369, 953.1088432315772, 955.1976339874504, 955.2930831184908, 995.4873240281261, 1001.9849702254436, 1002.939823610582, 1003.282652903174, 1004.6333088422746, 992.6277645882794, 996.2826764729383, 996.6063404955038, 996.8305388084007, 998.0, 727.0, 737.5206116024573, 738.9039424874732, 739.6190961654752, 740.4076738609091, 16322.918385408117, 16342.75485867713, 16413.343466261336, 16758.24587893548, 17010.659786804317, 11646.534138634444, 11972.760544789142, 14212.950285045703, 14228.92885535576, 14519.927400363036, 11849.17055806094, 11863.110266729944, 12012.399380030964, 12029.51881924722, 12085.758284834343, 3235.6205652673666, 3651.7626354286886, 3685.6130106338983, 3720.2559488102215, 3879.4568760373572, 117245.4137729306, 117873.41063294714, 118601.0, 122609.16086258713, 123439.0, 10859.13126949842, 10955.616553420592, 10996.450177491093, 11116.666500005012, 11399.544018239343, 22821.657675134888, 22979.540409191723, 23347.0, 23377.532449351256, 23415.0, 6628.502862285348, 6703.12859328294, 7680.193579674165, 7793.337566306927, 7823.608819558999, 4244.533101358848, 4277.465316835395, 4314.158739045856, 4319.9784001080425, 4372.672049596293, 3428.142964258935, 3472.097254713742, 3496.9825150874085, 3513.9297214055578, 3532.2405682778326, 540.3380858448369, 633.3444884544474, 640.8397900524869, 641.252940324526, 643.0932385336746, 639.7409049334934, 642.6537937863596, 646.1890327638783, 671.9563228390139, 685.5509641185032, 9311.882574091152, 9427.670031548863, 9686.612535498502, 9866.161376283097, 9922.107010369102, 563.3155715805316, 631.2046821005566, 637.5760119520467, 641.6695401868042, 646.0535315762337, 4356.99578194028, 4684.790332278242, 4695.600873925686, 4739.573438390561, 4812.975935120302, 105383.4730826341, 106544.40183397256, 107115.46442267895, 109582.90417095886, 110808.33787493194, 70014.64992675107, 75165.6241718788, 75515.0, 81415.0, 83372.58313708393, 80600.0, 81330.0, 83285.0, 83387.91530211671, 84362.73455898167, 8902.59938302791, 9148.725538233864, 9196.494192819458, 9299.582108651664, 9310.068993100636, 53444.73277633587, 53677.0, 53866.73066634642, 54920.72539637276, 55816.720916395156, 67889.32110679027, 68106.31893681, 69400.0, 70383.0, 70867.22831929244, 10348.948255258976, 10479.895201048197, 10762.461876905967, 10932.344059356465, 11095.22333436684, 9860.556274967787, 11988.580399685969, 12008.759824803281, 12113.81829272544, 12426.633070362072, 9045.095490450902, 10620.20348473883, 11578.189526733047, 11592.420378981185, 12238.816417753562, 11275.097992160772, 11648.359340236368, 11775.52897884075, 11843.0, 12118.971366332138, 9463.952680236554, 11010.669679909615, 11462.656120316404, 11490.655280341603, 11514.539418423505, 7889.487183333065, 7971.681132754625, 8019.117897031205, 8201.26188643025, 8226.383021273308, 9048.728538143867, 9129.817403652023, 9227.677031303872, 9636.865956431955, 9737.367071140354], "value": 7330.826199081283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 119.0, 120.0, 120.0, 120.0, 120.0, 120.0, 119.0, 119.0], "value": 118.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 114.0, 139.0, 117.0, 147.0, 136.0, 114.0, 130.0, 128.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 194.0, 199.0, 199.0, 198.0, 198.0, 192.0, 199.0, 200.0], "value": 197.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 63.0, 90.0, 63.0, 61.0, 62.0, 63.0, 75.0, 60.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 86.0, 72.0, 72.0, 69.0, 81.0, 72.0, 72.0, 83.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 89.0, 89.0, 88.0, 89.0, 89.0, 130.0, 137.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 126.0, 96.0, 112.0, 130.0, 152.0, 147.0, 95.0, 95.0, 96.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 91.0, 93.0, 89.0, 89.0, 138.0, 141.0, 91.0, 91.0], "value": 111.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 67.0, 68.0, 76.0, 78.0, 81.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 80.0, 52.0, 62.0, 103.0, 93.0, 87.0, 85.0, 85.0, 50.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 85.0, 73.0, 98.0, 105.0, 75.0, 106.0, 76.0, 108.0, 105.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 141.0, 133.0, 143.0, 152.0, 150.0, 147.0, 148.0, 210.0, 167.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 167.0, 163.0, 168.0, 172.0, 204.0, 169.0, 167.0, 164.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 76.0, 82.0, 88.0, 87.0, 61.0, 59.0, 83.0], "value": 73.5, "unit": "score"}]}]} 02:40:00 INFO - Return code: 0 02:40:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:40:00 ERROR - # TBPL SUCCESS # 02:40:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:40:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:40:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:40:00 INFO - 2017-04-11 02:40:00.292 screenresolution[1658:10730] starting screenresolution argv=screenresolution get 02:40:00 INFO - 2017-04-11 02:40:00.295 screenresolution[1658:10730] Display 0: 1600x1200x32@60 02:40:00 INFO - 2017-04-11 02:40:00.301 screenresolution[1659:10733] starting screenresolution argv=screenresolution list 02:40:00 INFO - Available Modes on Display 0 02:40:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:40:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:40:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:40:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:40:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:40:00 INFO - Intel Iris: 02:40:00 INFO - Chipset Model: Intel Iris 02:40:00 INFO - Type: GPU 02:40:00 INFO - Bus: Built-In 02:40:00 INFO - VRAM (Dynamic, Max): 1536 MB 02:40:00 INFO - Vendor: Intel (0x8086) 02:40:00 INFO - Device ID: 0x0a2e 02:40:00 INFO - Revision ID: 0x0009 02:40:00 INFO - Displays: 02:40:00 INFO - Display: 02:40:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:40:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:40:00 INFO - Display Serial Number: 1600x1200 60 02:40:00 INFO - Main Display: Yes 02:40:00 INFO - Mirror: Off 02:40:00 INFO - Online: Yes 02:40:00 INFO - Rotation: Supported 02:40:00 INFO - Return code: 0 02:40:00 INFO - Running post-action listener: _resource_record_post_action 02:40:00 INFO - [mozharness: 2017-04-11 09:40:00.534847Z] Finished run-tests step (success) 02:40:00 INFO - Running post-run listener: _resource_record_post_run 02:40:00 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39528448; Write bytes: 511465472; Read time: 250; Write time: 1608 02:40:00 INFO - TinderboxPrint: CPU usage
54.4% 02:40:00 INFO - TinderboxPrint: I/O read bytes / time
39,528,448 / 250 02:40:00 INFO - TinderboxPrint: I/O write bytes / time
511,465,472 / 1,608 02:40:00 INFO - TinderboxPrint: CPU idle
2,188.3 (45.6%) 02:40:00 INFO - TinderboxPrint: CPU system
961.6 (20.0%) 02:40:00 INFO - TinderboxPrint: CPU user
1,647.8 (34.3%) 02:40:00 INFO - TinderboxPrint: Swap in / out
247,136,256 / 0 02:40:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205904384; Write bytes: 201824256; Read time: 15703; Write time: 432 02:40:00 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37419008; Write bytes: 298577920; Read time: 230; Write time: 1139 02:40:01 INFO - Running post-run listener: _upload_blobber_files 02:40:01 INFO - Blob upload gear active. 02:40:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:40:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:40:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:40:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:40:01 INFO - (blobuploader) - INFO - Open directory for files ... 02:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:40:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:02 INFO - (blobuploader) - INFO - Done attempting. 02:40:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:40:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:40:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:03 INFO - (blobuploader) - INFO - Done attempting. 02:40:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:40:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:04 INFO - (blobuploader) - INFO - Done attempting. 02:40:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:40:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:40:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:40:07 INFO - (blobuploader) - INFO - Done attempting. 02:40:07 INFO - (blobuploader) - INFO - Iteration through files over. 02:40:07 INFO - Return code: 0 02:40:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:40:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:40:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2856c7a0a60f629ab82d045d9294106bbe48eeeda6d6f300953c9d70b74099529c8f177fcbb6431a6315af09b8cfd6825f3e5862d3f1db7914bf6197dc98b5f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5d73c04448316bd834b35446e805502695e5099ef4bc834de490c7670f054c23085fb1c5405c5297ad20b798ac1ecdb3a2f75e59270291cf653f50e81780e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e8a676183db952d05910c146e8fe1ffe28ab6fe7d376799434f30e2d81f3721b29a29db942d5ce1dd22d81720acd21bc8e53fa540ba414488f1ecd8b1d1081", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa1d7a6cc8e2167c823514a6c13ca320d9ede30b5317aa9b15bc783fb80efb17c8e8815bc4960c5aa2623d84c6a589992e7938753066b02da59442a71155364c"} 02:40:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:40:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:40:07 INFO - Contents: 02:40:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2856c7a0a60f629ab82d045d9294106bbe48eeeda6d6f300953c9d70b74099529c8f177fcbb6431a6315af09b8cfd6825f3e5862d3f1db7914bf6197dc98b5f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5d73c04448316bd834b35446e805502695e5099ef4bc834de490c7670f054c23085fb1c5405c5297ad20b798ac1ecdb3a2f75e59270291cf653f50e81780e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e8a676183db952d05910c146e8fe1ffe28ab6fe7d376799434f30e2d81f3721b29a29db942d5ce1dd22d81720acd21bc8e53fa540ba414488f1ecd8b1d1081", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa1d7a6cc8e2167c823514a6c13ca320d9ede30b5317aa9b15bc783fb80efb17c8e8815bc4960c5aa2623d84c6a589992e7938753066b02da59442a71155364c"} 02:40:07 INFO - Running post-run listener: copy_logs_to_upload_dir 02:40:07 INFO - Copying logs to upload dir... 02:40:07 INFO - mkdir: /builds/slave/test/build/upload/logs 02:40:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.600066 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-11 02:40:07.347305) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 02:40:07.351352) ========= 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.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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/2856c7a0a60f629ab82d045d9294106bbe48eeeda6d6f300953c9d70b74099529c8f177fcbb6431a6315af09b8cfd6825f3e5862d3f1db7914bf6197dc98b5f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5d73c04448316bd834b35446e805502695e5099ef4bc834de490c7670f054c23085fb1c5405c5297ad20b798ac1ecdb3a2f75e59270291cf653f50e81780e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e8a676183db952d05910c146e8fe1ffe28ab6fe7d376799434f30e2d81f3721b29a29db942d5ce1dd22d81720acd21bc8e53fa540ba414488f1ecd8b1d1081", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa1d7a6cc8e2167c823514a6c13ca320d9ede30b5317aa9b15bc783fb80efb17c8e8815bc4960c5aa2623d84c6a589992e7938753066b02da59442a71155364c"} build_url:https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/chuN5zhsROSpJbAVIedASw/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/2856c7a0a60f629ab82d045d9294106bbe48eeeda6d6f300953c9d70b74099529c8f177fcbb6431a6315af09b8cfd6825f3e5862d3f1db7914bf6197dc98b5f0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d5d73c04448316bd834b35446e805502695e5099ef4bc834de490c7670f054c23085fb1c5405c5297ad20b798ac1ecdb3a2f75e59270291cf653f50e81780e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7e8a676183db952d05910c146e8fe1ffe28ab6fe7d376799434f30e2d81f3721b29a29db942d5ce1dd22d81720acd21bc8e53fa540ba414488f1ecd8b1d1081", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa1d7a6cc8e2167c823514a6c13ca320d9ede30b5317aa9b15bc783fb80efb17c8e8815bc4960c5aa2623d84c6a589992e7938753066b02da59442a71155364c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 02:40:07.387249) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:40:07.387616) ========= 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.FLZjTyVpAE/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.00VHRaz1WG/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:40:07.436596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 02:40:07.436930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 02:40:07.437280) ========= ========= Total master_lag: 0.19 =========