builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0206 starttime: 1491847664.57 results: success (0) buildid: 20170410090449 builduid: ecfa5c23f9894a0e875526d98b40ba0e revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.566629) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.567609) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.568104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.594975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.595361) ========= 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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-10 11:07:44-- 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% 157M=0s 2017-04-10 11:07:44 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100024 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.723369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.723748) ========= 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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.023068 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:44.776058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:07:44.776481) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e774d2cb0fc5b487607d452dc38715acb4040cc7 --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 e774d2cb0fc5b487607d452dc38715acb4040cc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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-10 11:07:44,868 truncating revision to first 12 chars 2017-04-10 11:07:44,868 Setting DEBUG logging. 2017-04-10 11:07:44,868 attempt 1/10 2017-04-10 11:07:44,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e774d2cb0fc5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 11:07:45,627 unpacking tar archive at: mozilla-aurora-e774d2cb0fc5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.021962 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:07:45.826415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:45.827128) ========= script_repo_revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:45.827532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:45.827819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 11:07:45.847736) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-10 11:07:45.848428) ========= /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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:07:46 INFO - MultiFileLogger online at 20170410 11:07:46 in /builds/slave/test 11:07:46 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 11:07:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:07:46 INFO - {'append_to_log': False, 11:07:46 INFO - 'base_work_dir': '/builds/slave/test', 11:07:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:07:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:07:46 INFO - 'branch': 'Mozilla-Aurora', 11:07:46 INFO - 'buildbot_json_path': 'buildprops.json', 11:07:46 INFO - 'config_files': ('talos/mac_config.py',), 11:07:46 INFO - 'default_actions': ('clobber', 11:07:46 INFO - 'read-buildbot-config', 11:07:46 INFO - 'download-and-extract', 11:07:46 INFO - 'populate-webroot', 11:07:46 INFO - 'create-virtualenv', 11:07:46 INFO - 'install', 11:07:46 INFO - 'run-tests'), 11:07:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:07:46 INFO - 'download_minidump_stackwalk': True, 11:07:46 INFO - 'download_symbols': 'ondemand', 11:07:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:07:46 INFO - 'tooltool.py': '/tools/tooltool.py', 11:07:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:07:46 INFO - '/tools/misc-python/virtualenv.py')}, 11:07:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:07:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:07:46 INFO - 'gecko_profile': False, 11:07:46 INFO - 'gecko_profile_interval': 0, 11:07:46 INFO - 'installer_path': 'installer.exe', 11:07:46 INFO - 'log_level': 'info', 11:07:46 INFO - 'log_name': 'talos', 11:07:46 INFO - 'log_to_console': True, 11:07:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:07:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:07:46 INFO - 'opt_config_files': (), 11:07:46 INFO - 'pip_index': False, 11:07:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:46 INFO - 'cmd': ('bash', 11:07:46 INFO - '-c', 11:07:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:46 INFO - 'enabled': True, 11:07:46 INFO - 'halt_on_failure': False, 11:07:46 INFO - 'name': 'check_screen_resolution'},), 11:07:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:07:46 INFO - 'cmd': ('bash', 11:07:46 INFO - '-c', 11:07:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:07:46 INFO - 'enabled': True, 11:07:46 INFO - 'halt_on_failure': False, 11:07:46 INFO - 'name': 'check_screen_resolution'},), 11:07:46 INFO - 'run_cmd_checks_enabled': True, 11:07:46 INFO - 'suite': 'dromaeojs-e10s', 11:07:46 INFO - 'system_bits': '32', 11:07:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:07:46 INFO - 'title': 't-yosemite-r7-0206', 11:07:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:07:46 INFO - 'use_talos_json': True, 11:07:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:07:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:07:46 INFO - 'work_dir': 'build'} 11:07:46 INFO - [mozharness: 2017-04-10 18:07:46.066411Z] Running clobber step. 11:07:46 INFO - Running pre-action listener: _resource_record_pre_action 11:07:46 INFO - Running main action method: clobber 11:07:46 INFO - rmtree: /builds/slave/test/build 11:07:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:07:47 INFO - Running post-action listener: _resource_record_post_action 11:07:47 INFO - [mozharness: 2017-04-10 18:07:47.842935Z] Finished clobber step (success) 11:07:47 INFO - [mozharness: 2017-04-10 18:07:47.843164Z] Running read-buildbot-config step. 11:07:47 INFO - Running pre-action listener: _resource_record_pre_action 11:07:47 INFO - Running main action method: read_buildbot_config 11:07:47 INFO - Using buildbot properties: 11:07:47 INFO - { 11:07:47 INFO - "project": "", 11:07:47 INFO - "product": "firefox", 11:07:47 INFO - "script_repo_revision": "production", 11:07:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:07:47 INFO - "repository": "", 11:07:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:07:47 INFO - "buildid": "20170410090449", 11:07:47 INFO - "pgo_build": "False", 11:07:47 INFO - "basedir": "/builds/slave/test", 11:07:47 INFO - "buildnumber": 14, 11:07:47 INFO - "slavename": "t-yosemite-r7-0206", 11:07:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:07:47 INFO - "platform": "yosemite_r7", 11:07:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:07:47 INFO - "branch": "mozilla-aurora", 11:07:47 INFO - "repo_path": "releases/mozilla-aurora", 11:07:47 INFO - "revision": "e774d2cb0fc5b487607d452dc38715acb4040cc7", 11:07:47 INFO - "stage_platform": "macosx64", 11:07:47 INFO - "builduid": "ecfa5c23f9894a0e875526d98b40ba0e", 11:07:47 INFO - "slavebuilddir": "test" 11:07:47 INFO - } 11:07:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 11:07:47 INFO - Running post-action listener: _resource_record_post_action 11:07:47 INFO - [mozharness: 2017-04-10 18:07:47.849016Z] Finished read-buildbot-config step (success) 11:07:47 INFO - [mozharness: 2017-04-10 18:07:47.849246Z] Running download-and-extract step. 11:07:47 INFO - Running pre-action listener: _resource_record_pre_action 11:07:47 INFO - Running main action method: download_and_extract 11:07:47 INFO - mkdir: /builds/slave/test/build/tests 11:07: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')]} 11:07:47 INFO - trying https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 11:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:07:48 INFO - Downloaded 1199 bytes. 11:07:48 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:07:48 INFO - Using the following test package requirements: 11:07:48 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:07:48 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 11:07:48 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 11:07:48 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'jsshell-mac.zip'], 11:07:48 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 11:07:48 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:07:48 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 11:07:48 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 11:07:48 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 11:07:48 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:07:48 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 11:07:48 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:07:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:07:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:07:48 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:07:49 INFO - Expected file size: 51790751 11:07:49 INFO - Obtained file size: 51790751 11:08: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 11:08:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:08:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:08:05 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:08:07 INFO - Expected file size: 51790751 11:08:07 INFO - Obtained file size: 51790751 11:08:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 11:08:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:08:22 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 11:08:23 INFO - Expected file size: 14404424 11:08:23 INFO - Obtained file size: 14404424 11:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:23 INFO - trying https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:23 INFO - Downloading https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 11:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:08:25 INFO - Downloaded 74689750 bytes. 11:08:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:25 INFO - mkdir: /builds/slave/test/properties 11:08:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:08:25 INFO - Writing to file /builds/slave/test/properties/build_url 11:08:25 INFO - Contents: 11:08:25 INFO - build_url:https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:26 INFO - Running post-action listener: _resource_record_post_action 11:08:26 INFO - Running post-action listener: set_extra_try_arguments 11:08:26 INFO - [mozharness: 2017-04-10 18:08:26.273846Z] Finished download-and-extract step (success) 11:08:26 INFO - [mozharness: 2017-04-10 18:08:26.273989Z] Running populate-webroot step. 11:08:26 INFO - Running pre-action listener: _resource_record_pre_action 11:08:26 INFO - Running main action method: populate_webroot 11:08:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:08:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:08:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:08:26 INFO - '--tppagecycles', 11:08:26 INFO - '7'], 11:08:26 INFO - 'tests': ['tsvgm']}}, 11:08:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:08:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:08:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:08:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:08:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['damp', 'tps']}, 11:08:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'tests': ['damp', 'tps']}, 11:08:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['dromaeo_dom']}, 11:08:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:08:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['basic_compositor_video']}, 11:08:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:08:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'other-e10s': {'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['a11yr', 11:08:26 INFO - 'ts_paint', 11:08:26 INFO - 'tpaint', 11:08:26 INFO - 'sessionrestore', 11:08:26 INFO - 'sessionrestore_no_auto_restore', 11:08:26 INFO - 'tabpaint']}, 11:08:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['tsvgx', 11:08:26 INFO - 'tsvgr_opacity', 11:08:26 INFO - 'tart', 11:08:26 INFO - 'tscrollx', 11:08:26 INFO - 'cart', 11:08:26 INFO - 'tsvg_static']}, 11:08:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:08:26 INFO - 'tsvgr_opacity', 11:08:26 INFO - 'tart', 11:08:26 INFO - 'tscrollx', 11:08:26 INFO - 'cart', 11:08:26 INFO - 'tsvg_static']}, 11:08:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'talos_options': ['--disable-e10s'], 11:08:26 INFO - 'tests': ['tp5o']}, 11:08:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:08:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'talos_options': ['--disable-e10s', 11:08:26 INFO - '--xperf_path', 11:08:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:26 INFO - 'tests': ['tp5n']}, 11:08:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:08:26 INFO - 'talos_options': ['--xperf_path', 11:08:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:26 INFO - 'tests': ['tp5n']}}, 11:08:26 INFO - 'talos.zip': {'path': '', 11:08:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:08:26 INFO - Running post-action listener: _resource_record_post_action 11:08:26 INFO - [mozharness: 2017-04-10 18:08:26.292563Z] Finished populate-webroot step (success) 11:08:26 INFO - [mozharness: 2017-04-10 18:08:26.292700Z] Running create-virtualenv step. 11:08:26 INFO - Running pre-action listener: _resource_record_pre_action 11:08:26 INFO - Running main action method: create_virtualenv 11:08:26 INFO - Creating virtualenv /builds/slave/test/build/venv 11:08: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 11:08:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:08:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:08:26 INFO - Using real prefix '/tools/python27' 11:08:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:08:27 INFO - Installing distribute.............................................................................................................................................................................................done. 11:08:30 INFO - Installing pip.................done. 11:08:30 INFO - Return code: 0 11:08:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:08:30 INFO - Reading from file tmpfile_stdout 11:08:30 INFO - Output received: 11:08: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) 11:08:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08: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 11:08: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 11:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:30 INFO - 'HOME': '/Users/cltbld', 11:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:30 INFO - 'LOGNAME': 'cltbld', 11:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:30 INFO - 'MOZ_NO_REMOTE': '1', 11:08:30 INFO - 'NO_EM_RESTART': '1', 11:08:30 INFO - 'PAGER': '/bin/cat', 11:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:30 INFO - 'PWD': '/builds/slave/test', 11:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:30 INFO - 'SHELL': '/bin/bash', 11:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:30 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:30 INFO - 'USER': 'cltbld', 11:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:30 INFO - 'XPC_FLAGS': '0x0', 11:08:30 INFO - 'XPC_SERVICE_NAME': '0', 11:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:30 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 11:08:30 INFO - Cleaning up... 11:08:30 INFO - Return code: 0 11:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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', '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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:08: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 psutil>=3.1.1 11:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:30 INFO - 'HOME': '/Users/cltbld', 11:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:30 INFO - 'LOGNAME': 'cltbld', 11:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:30 INFO - 'MOZ_NO_REMOTE': '1', 11:08:30 INFO - 'NO_EM_RESTART': '1', 11:08:30 INFO - 'PAGER': '/bin/cat', 11:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:30 INFO - 'PWD': '/builds/slave/test', 11:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:30 INFO - 'SHELL': '/bin/bash', 11:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:30 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:30 INFO - 'USER': 'cltbld', 11:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:30 INFO - 'XPC_FLAGS': '0x0', 11:08:30 INFO - 'XPC_SERVICE_NAME': '0', 11:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:30 INFO - Downloading/unpacking psutil>=3.1.1 11:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:08:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:34 INFO - Installing collected packages: psutil 11:08:34 INFO - Running setup.py install for psutil 11:08:34 INFO - building 'psutil._psutil_osx' extension 11:08:34 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 11:08:34 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 11:08:34 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 11:08:34 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 11:08:34 INFO - building 'psutil._psutil_posix' extension 11:08:34 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 11:08:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:08:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:08:34 INFO - ^ 11:08:34 INFO - 1 warning generated. 11:08:34 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 11:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:08:35 INFO - Successfully installed psutil 11:08:35 INFO - Cleaning up... 11:08:35 INFO - Return code: 0 11:08:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:08:35 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')]} 11:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:35 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')]} 11:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:35 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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08:35 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 11:08:35 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 11:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:35 INFO - 'HOME': '/Users/cltbld', 11:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:35 INFO - 'LOGNAME': 'cltbld', 11:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:35 INFO - 'MOZ_NO_REMOTE': '1', 11:08:35 INFO - 'NO_EM_RESTART': '1', 11:08:35 INFO - 'PAGER': '/bin/cat', 11:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:35 INFO - 'PWD': '/builds/slave/test', 11:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:35 INFO - 'SHELL': '/bin/bash', 11:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:35 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:35 INFO - 'USER': 'cltbld', 11:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:35 INFO - 'XPC_FLAGS': '0x0', 11:08:35 INFO - 'XPC_SERVICE_NAME': '0', 11:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:08:35 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 11:08:35 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 11:08:35 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 11:08:35 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 11:08:35 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 11:08:35 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 11:08:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:08:38 INFO - Installing collected packages: mozsystemmonitor 11:08:38 INFO - Running setup.py install for mozsystemmonitor 11:08:38 INFO - Successfully installed mozsystemmonitor 11:08:38 INFO - Cleaning up... 11:08:38 INFO - Return code: 0 11:08:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:08:38 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')]} 11:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:38 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')]} 11:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:38 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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08:38 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 11:08:38 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 11:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:38 INFO - 'HOME': '/Users/cltbld', 11:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:38 INFO - 'LOGNAME': 'cltbld', 11:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:38 INFO - 'MOZ_NO_REMOTE': '1', 11:08:38 INFO - 'NO_EM_RESTART': '1', 11:08:38 INFO - 'PAGER': '/bin/cat', 11:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:38 INFO - 'PWD': '/builds/slave/test', 11:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:38 INFO - 'SHELL': '/bin/bash', 11:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:38 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:38 INFO - 'USER': 'cltbld', 11:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:38 INFO - 'XPC_FLAGS': '0x0', 11:08:38 INFO - 'XPC_SERVICE_NAME': '0', 11:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:38 INFO - Downloading/unpacking jsonschema==2.5.1 11:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:08:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:08:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:08:41 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 11:08:41 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 11:08:41 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 11:08:41 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 11:08:41 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 11:08:41 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 11:08:41 INFO - Downloading functools32-3.2.3-2.tar.gz 11:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:08:41 INFO - warning: no files found matching '*.txt' 11:08:41 INFO - no previously-included directories found matching 'build' 11:08:41 INFO - no previously-included directories found matching 'dist' 11:08:41 INFO - no previously-included directories found matching '.git*' 11:08:41 INFO - Installing collected packages: jsonschema, functools32 11:08:41 INFO - Running setup.py install for functools32 11:08:41 INFO - warning: no files found matching '*.txt' 11:08:41 INFO - no previously-included directories found matching 'build' 11:08:41 INFO - no previously-included directories found matching 'dist' 11:08:41 INFO - no previously-included directories found matching '.git*' 11:08:41 INFO - Successfully installed jsonschema functools32 11:08:41 INFO - Cleaning up... 11:08:42 INFO - Return code: 0 11:08:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:42 INFO - 'HOME': '/Users/cltbld', 11:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:42 INFO - 'LOGNAME': 'cltbld', 11:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:42 INFO - 'MOZ_NO_REMOTE': '1', 11:08:42 INFO - 'NO_EM_RESTART': '1', 11:08:42 INFO - 'PAGER': '/bin/cat', 11:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:42 INFO - 'PWD': '/builds/slave/test', 11:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:42 INFO - 'SHELL': '/bin/bash', 11:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:42 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:42 INFO - 'USER': 'cltbld', 11:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:42 INFO - 'XPC_FLAGS': '0x0', 11:08:42 INFO - 'XPC_SERVICE_NAME': '0', 11:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:08:43 INFO - Cleaning up... 11:08:43 INFO - Return code: 0 11:08:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:08:43 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')]} 11:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:43 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')]} 11:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:43 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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08:43 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 11:08:43 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 11:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:43 INFO - 'HOME': '/Users/cltbld', 11:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:43 INFO - 'LOGNAME': 'cltbld', 11:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:43 INFO - 'MOZ_NO_REMOTE': '1', 11:08:43 INFO - 'NO_EM_RESTART': '1', 11:08:43 INFO - 'PAGER': '/bin/cat', 11:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:43 INFO - 'PWD': '/builds/slave/test', 11:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:43 INFO - 'SHELL': '/bin/bash', 11:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:43 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:43 INFO - 'USER': 'cltbld', 11:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:43 INFO - 'XPC_FLAGS': '0x0', 11:08:43 INFO - 'XPC_SERVICE_NAME': '0', 11:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:43 INFO - Downloading/unpacking blobuploader==1.2.4 11:08: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 11:08: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 11:08: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 11:08: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 11:08: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 11:08: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 11:08:46 INFO - Downloading blobuploader-1.2.4.tar.gz 11:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:08:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:08:46 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 11:08:46 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 11:08:46 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 11:08:46 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 11:08:46 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 11:08:46 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 11:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:08:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:08: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 11:08: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 11:08: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 11:08: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 11:08: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 11:08: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 11:08:47 INFO - Downloading docopt-0.6.1.tar.gz 11:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:08:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:08:48 INFO - Running setup.py install for blobuploader 11:08:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:08:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:08:48 INFO - Running setup.py install for requests 11:08:48 INFO - Running setup.py install for docopt 11:08:48 INFO - Successfully installed blobuploader requests docopt 11:08:48 INFO - Cleaning up... 11:08:49 INFO - Return code: 0 11:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08: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 11:08: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 11:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:49 INFO - 'HOME': '/Users/cltbld', 11:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:49 INFO - 'LOGNAME': 'cltbld', 11:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:49 INFO - 'MOZ_NO_REMOTE': '1', 11:08:49 INFO - 'NO_EM_RESTART': '1', 11:08:49 INFO - 'PAGER': '/bin/cat', 11:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:49 INFO - 'PWD': '/builds/slave/test', 11:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:49 INFO - 'SHELL': '/bin/bash', 11:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:49 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:49 INFO - 'USER': 'cltbld', 11:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:49 INFO - 'XPC_FLAGS': '0x0', 11:08:49 INFO - 'XPC_SERVICE_NAME': '0', 11:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-IUcH3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-pk1p4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-hg41f5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-N7tdO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-PZRMvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-RC4hTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:49 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-_AJii5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-oVsLPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ZNLDzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-N4OlHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-FnPWzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-VBWq54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ouWevY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-7oJz38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Ae33zV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:50 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-MbQvJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:51 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Td81Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:08:51 INFO - Running setup.py install for manifestparser 11:08:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:08:51 INFO - Running setup.py install for mozcrash 11:08:51 INFO - Running setup.py install for mozdebug 11:08:51 INFO - Running setup.py install for mozdevice 11:08:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:08:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:08:51 INFO - Running setup.py install for mozfile 11:08:51 INFO - Running setup.py install for mozhttpd 11:08:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Running setup.py install for mozinfo 11:08:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Running setup.py install for mozInstall 11:08:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Running setup.py install for mozleak 11:08:52 INFO - Running setup.py install for mozlog 11:08:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Running setup.py install for moznetwork 11:08:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:08:52 INFO - Running setup.py install for mozprocess 11:08:52 INFO - Running setup.py install for mozprofile 11:08:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:08:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:08:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:08:53 INFO - Running setup.py install for mozrunner 11:08:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:08:53 INFO - Running setup.py install for mozscreenshot 11:08:53 INFO - Running setup.py install for moztest 11:08:53 INFO - Running setup.py install for mozversion 11:08:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:08:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:08:53 INFO - Cleaning up... 11:08:53 INFO - Return code: 0 11:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08:53 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')]} 11:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:53 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')]} 11:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:53 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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08:53 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 11:08:53 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 11:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:53 INFO - 'HOME': '/Users/cltbld', 11:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:53 INFO - 'LOGNAME': 'cltbld', 11:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:53 INFO - 'MOZ_NO_REMOTE': '1', 11:08:53 INFO - 'NO_EM_RESTART': '1', 11:08:53 INFO - 'PAGER': '/bin/cat', 11:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:53 INFO - 'PWD': '/builds/slave/test', 11:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:53 INFO - 'SHELL': '/bin/bash', 11:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:53 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:53 INFO - 'USER': 'cltbld', 11:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:53 INFO - 'XPC_FLAGS': '0x0', 11:08:53 INFO - 'XPC_SERVICE_NAME': '0', 11:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-ou6MeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:08: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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-c1kj72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:08: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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-4vSCUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:08: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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-smwuft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:08:54 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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-B9odCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:08:54 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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-p6UT19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:08:54 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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-xkE3Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:08:54 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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-DbSZ8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:08:54 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)) 11:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:08:54 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-J3Oqum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:08: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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-G4Js86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:08: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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-sFeTc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:08: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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Qyd1lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:08: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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-EHLks8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:08: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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Hx5ZNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:08:55 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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-Y3hmFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:08:55 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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-wjxdRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:08:55 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)) 11:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:08:55 INFO - Running setup.py (path:/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/pip-bvd_3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:08:55 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)) 11:08:55 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)) 11:08:55 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)) 11:08:55 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)) 11:08:55 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)) 11:08:55 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)) 11:08:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:08:55 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 11:08:55 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 11:08:55 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 11:08:55 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 11:08:55 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 11:08:55 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 11:08:58 INFO - Downloading blessings-1.6.tar.gz 11:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:08:58 INFO - Installing collected packages: blessings 11:08:58 INFO - Running setup.py install for blessings 11:08:58 INFO - Successfully installed blessings 11:08:58 INFO - Cleaning up... 11:08:58 INFO - Return code: 0 11:08:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:08:59 INFO - Reading from file tmpfile_stdout 11:08:59 INFO - Current package versions: 11:08:59 INFO - blessings == 1.6 11:08:59 INFO - blobuploader == 1.2.4 11:08:59 INFO - docopt == 0.6.1 11:08:59 INFO - functools32 == 3.2.3-2 11:08:59 INFO - jsonschema == 2.5.1 11:08:59 INFO - manifestparser == 1.1 11:08:59 INFO - mozInstall == 1.12 11:08:59 INFO - mozcrash == 1.0 11:08:59 INFO - mozdebug == 0.1 11:08:59 INFO - mozdevice == 0.49 11:08:59 INFO - mozfile == 1.2 11:08:59 INFO - mozhttpd == 0.7 11:08:59 INFO - mozinfo == 0.9 11:08:59 INFO - mozleak == 0.1 11:08:59 INFO - mozlog == 3.4 11:08:59 INFO - moznetwork == 0.27 11:08:59 INFO - mozprocess == 0.25 11:08:59 INFO - mozprofile == 0.28 11:08:59 INFO - mozrunner == 6.13 11:08:59 INFO - mozscreenshot == 0.1 11:08:59 INFO - mozsystemmonitor == 0.3 11:08:59 INFO - moztest == 0.8 11:08:59 INFO - mozversion == 1.4 11:08:59 INFO - psutil == 3.1.1 11:08:59 INFO - requests == 1.2.3 11:08:59 INFO - wsgiref == 0.1.2 11:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08: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 11:08: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 11:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:59 INFO - 'HOME': '/Users/cltbld', 11:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:59 INFO - 'LOGNAME': 'cltbld', 11:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:59 INFO - 'MOZ_NO_REMOTE': '1', 11:08:59 INFO - 'NO_EM_RESTART': '1', 11:08:59 INFO - 'PAGER': '/bin/cat', 11:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:59 INFO - 'PWD': '/builds/slave/test', 11:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:59 INFO - 'SHELL': '/bin/bash', 11:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:59 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:59 INFO - 'USER': 'cltbld', 11:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:59 INFO - 'XPC_FLAGS': '0x0', 11:08:59 INFO - 'XPC_SERVICE_NAME': '0', 11:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08: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)) 11:08:59 INFO - Cleaning up... 11:08:59 INFO - Return code: 0 11:08:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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 0x10dcecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dcef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b324c2c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, '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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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 11:08: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 11:08: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 11:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:59 INFO - 'HOME': '/Users/cltbld', 11:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:59 INFO - 'LOGNAME': 'cltbld', 11:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:59 INFO - 'MOZ_NO_REMOTE': '1', 11:08:59 INFO - 'NO_EM_RESTART': '1', 11:08:59 INFO - 'PAGER': '/bin/cat', 11:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:59 INFO - 'PWD': '/builds/slave/test', 11:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:59 INFO - 'SHELL': '/bin/bash', 11:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:08:59 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:59 INFO - 'USER': 'cltbld', 11:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:59 INFO - 'XPC_FLAGS': '0x0', 11:08:59 INFO - 'XPC_SERVICE_NAME': '0', 11:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:08:59 INFO - Cleaning up... 11:08:59 INFO - Return code: 0 11:08:59 INFO - Running post-action listener: _resource_record_post_action 11:08:59 INFO - Running post-action listener: _start_resource_monitoring 11:08:59 INFO - Starting resource monitoring. 11:08:59 INFO - [mozharness: 2017-04-10 18:08:59.780715Z] Finished create-virtualenv step (success) 11:08:59 INFO - [mozharness: 2017-04-10 18:08:59.781716Z] Running install step. 11:08:59 INFO - Running pre-action listener: _resource_record_pre_action 11:08:59 INFO - Running main action method: install 11:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:00 INFO - Reading from file tmpfile_stdout 11:09:00 INFO - Detecting whether we're running mozinstall >=1.0... 11:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:09:00 INFO - Reading from file tmpfile_stdout 11:09:00 INFO - Output received: 11:09:00 INFO - Usage: mozinstall [options] installer 11:09:00 INFO - Options: 11:09:00 INFO - -h, --help show this help message and exit 11:09:00 INFO - -d DEST, --destination=DEST 11:09:00 INFO - Directory to install application into. [default: 11:09:00 INFO - "/builds/slave/test"] 11:09:00 INFO - --app=APP Application being installed. [default: firefox] 11:09:00 INFO - mkdir: /builds/slave/test/build/application 11:09: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'] 11:09: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 11:09:21 INFO - Reading from file tmpfile_stdout 11:09:21 INFO - Output received: 11:09:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:09:21 INFO - Running post-action listener: _resource_record_post_action 11:09:21 INFO - [mozharness: 2017-04-10 18:09:21.083253Z] Finished install step (success) 11:09:21 INFO - [mozharness: 2017-04-10 18:09:21.083418Z] Running run-tests step. 11:09:21 INFO - Running pre-action listener: _resource_record_pre_action 11:09:21 INFO - Running main action method: run_tests 11:09:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:21 INFO - 2017-04-10 11:09:21.098 screenresolution[2930:46301] starting screenresolution argv=screenresolution get 11:09:21 INFO - 2017-04-10 11:09:21.101 screenresolution[2930:46301] Display 0: 1600x1200x32@60 11:09:21 INFO - 2017-04-10 11:09:21.107 screenresolution[2931:46305] starting screenresolution argv=screenresolution list 11:09:21 INFO - Available Modes on Display 0 11:09:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:21 INFO - Intel Iris: 11:09:21 INFO - Chipset Model: Intel Iris 11:09:21 INFO - Type: GPU 11:09:21 INFO - Bus: Built-In 11:09:21 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:21 INFO - Vendor: Intel (0x8086) 11:09:21 INFO - Device ID: 0x0a2e 11:09:21 INFO - Revision ID: 0x0009 11:09:21 INFO - Displays: 11:09:21 INFO - Display: 11:09:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:21 INFO - Display Serial Number: 1600x1200 60 11:09:21 INFO - Main Display: Yes 11:09:21 INFO - Mirror: Off 11:09:21 INFO - Online: Yes 11:09:21 INFO - Rotation: Supported 11:09:21 INFO - Return code: 0 11:09:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:09:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:09:21 INFO - Python 2.7.3 11:09:21 INFO - Return code: 0 11:09:21 INFO - grabbing minidump binary from tooltool 11:09:21 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')]} 11:09:21 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 0x10dacad30>, '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 0x10d9f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dabfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:09:21 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 11:09:21 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 11:09:21 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 11:09:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:09:21 INFO - Return code: 0 11:09:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:09:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:09:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:09:21 INFO - ENV: RUST_BACKTRACE is now 1 11:09:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:09:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:09:21 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/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 11:09:21 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/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0206 --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 11:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G4SLPkDgAN/Render', 11:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:21 INFO - 'HOME': '/Users/cltbld', 11:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:21 INFO - 'LOGNAME': 'cltbld', 11:09:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:09:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:21 INFO - 'MOZ_NO_REMOTE': '1', 11:09:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:09:21 INFO - 'NO_EM_RESTART': '1', 11:09:21 INFO - 'PAGER': '/bin/cat', 11:09:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:21 INFO - 'PWD': '/builds/slave/test', 11:09:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:21 INFO - 'RUST_BACKTRACE': '1', 11:09:21 INFO - 'SHELL': '/bin/bash', 11:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7eEmzmMyHN/Listeners', 11:09:21 INFO - 'TMPDIR': '/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/', 11:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:21 INFO - 'USER': 'cltbld', 11:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:21 INFO - 'XPC_FLAGS': '0x0', 11:09:21 INFO - 'XPC_SERVICE_NAME': '0', 11:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:21 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/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0206', '--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 11:09:21 INFO - mozversion application_buildid: 20170410090449 11:09:21 INFO - mozversion application_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 11:09:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:09:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:09:21 INFO - mozversion application_name: Firefox 11:09:21 INFO - mozversion application_remotingname: firefox-dev 11:09:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:09:21 INFO - mozversion application_vendor: Mozilla 11:09:21 INFO - mozversion application_version: 54.0a2 11:09:21 INFO - mozversion platform_buildid: 20170410090449 11:09:21 INFO - mozversion platform_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 11:09:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:09:21 INFO - mozversion platform_version: 54.0a2 11:09:21 INFO - using testdate: 1491847761 11:09:21 INFO - actual date: 1491847761 11:09:21 INFO - starting webserver on 'localhost:62539' 11:09:21 INFO - SUITE-START | Running 2 tests 11:09:21 INFO - TEST-START | dromaeo_css 11:09:21 INFO - operating with platform_type : mac_ 11:09:21 INFO - Initialising browser for dromaeo_css test... 11:09:21 INFO - TEST-INFO | started process 2941 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpTlbLUV/profile http://localhost:62539/getInfo.html) 11:09:24 INFO - PROCESS | 2941 | 2017-04-10 11:09:24.334 plugin-container[2943:46474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 11:09:24 INFO - PROCESS | 2941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:24 INFO - PROCESS | 2941 | 2017-04-10 11:09:24.337 plugin-container[2943:46474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.CFPasteboardClient' 11:09:24 INFO - PROCESS | 2941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:24 INFO - PROCESS | 2941 | 2017-04-10 11:09:24.337 plugin-container[2943:46474] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:24 INFO - PROCESS | 2941 | __metrics Screen width/height:1600/1200 11:09:24 INFO - PROCESS | 2941 | colorDepth:24 11:09:24 INFO - PROCESS | 2941 | Browser inner width/height: 1024/689 11:09:24 INFO - PROCESS | 2941 | __metrics 11:09:24 ERROR - PROCESS | 2941 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 11:09:25 INFO - TEST-INFO | 2941: exit 0 11:09:25 INFO - Browser initialized. 11:09:25 INFO - Running cycle 1/1 for dromaeo_css test... 11:09:25 INFO - TEST-INFO | started process 2944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpTlbLUV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:09:27 INFO - PROCESS | 2944 | 2017-04-10 11:09:27.689 plugin-container[2945:46608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882b, name = 'com.apple.tsm.portname' 11:09:27 INFO - PROCESS | 2944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:27 INFO - PROCESS | 2944 | 2017-04-10 11:09:27.690 plugin-container[2945:46608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 11:09:27 INFO - PROCESS | 2944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:09:27 INFO - PROCESS | 2944 | 2017-04-10 11:09:27.690 plugin-container[2945:46608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:09:37 INFO - PROCESS | 2944 | RSS: Main: 220590080 11:09:37 INFO - PROCESS | 2944 | 11:12:41 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62539/tests/dromaeo/cssquery-ext.html) 11:12:43 INFO - PROCESS | 2944 | RSS: Main: 241844224 11:12:43 INFO - PROCESS | 2944 | 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | A coding exception was thrown and uncaught in a Task. 11:14:25 INFO - PROCESS | 2944 | 11:14:25 ERROR - PROCESS | 2944 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:25 INFO - PROCESS | 2944 | Full stack: 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | A coding exception was thrown and uncaught in a Task. 11:14:25 INFO - PROCESS | 2944 | 11:14:25 ERROR - PROCESS | 2944 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:25 INFO - PROCESS | 2944 | Full stack: 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | A coding exception was thrown and uncaught in a Task. 11:14:25 INFO - PROCESS | 2944 | 11:14:25 ERROR - PROCESS | 2944 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:25 INFO - PROCESS | 2944 | Full stack: 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | ************************* 11:14:25 INFO - PROCESS | 2944 | A coding exception was thrown and uncaught in a Task. 11:14:25 INFO - PROCESS | 2944 | 11:14:25 ERROR - PROCESS | 2944 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:25 INFO - PROCESS | 2944 | Full stack: 11:14:25 INFO - PROCESS | 2944 | ************************* 11:15:45 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-ext.html (next: http://localhost:62539/tests/dromaeo/cssquery-jquery.html) 11:15:46 INFO - PROCESS | 2944 | RSS: Main: 251109376 11:15:46 INFO - PROCESS | 2944 | 11:18:48 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62539/tests/dromaeo/cssquery-mootools.html) 11:18:48 INFO - PROCESS | 2944 | RSS: Main: 258670592 11:18:48 INFO - PROCESS | 2944 | 11:21:50 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62539/tests/dromaeo/cssquery-prototype.html) 11:21:51 INFO - PROCESS | 2944 | RSS: Main: 254918656 11:21:51 INFO - PROCESS | 2944 | 11:24:54 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62539/tests/dromaeo/cssquery-yui.html) 11:24:55 INFO - PROCESS | 2944 | RSS: Main: 259911680 11:24:55 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | Cycle 1(1): loaded http://localhost:62539/tests/dromaeo/cssquery-yui.html (next: http://localhost:62539/tests/dromaeo/cssquery-dojo.html) 11:27:58 INFO - PROCESS | 2944 | RSS: Main: 256688128 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | __start_tp_report 11:27:58 INFO - PROCESS | 2944 | _x_x_mozilla_page_load 11:27:58 INFO - PROCESS | 2944 | _x_x_mozilla_page_load_details 11:27:58 INFO - PROCESS | 2944 | |i|pagename|runs| 11:27:58 INFO - PROCESS | 2944 | |0;dojo.html;2973.4647763402586;3465.3589086019088;3487.4245749451343;3490.9650903490956;3511.9122021949447;6121.112438696387;6129.509639228862;6153.046277826933;6163.691815409234;6175.75296988121;6113.2664080310315;6129.570930034899;6174.536909731765;6196.659183744903;6218.968905155479;4657.743824089674;5320.866978325554;5520.530754885839;5551.2228288039705;5614.129809879455;5567.832965011056;5579.163125531162;5598.676549232064;5613.46672066153;5631.943680563203;5066.240063990394;5484.61607687462;5581.5255703265275;5583.35791383992;5606.943930560683;5498.945010549884;5514.834554963358;5528.585356098309;5555.638883472581;5563.443655634444;5400.594955378323;5521.641093328921;5530.557555395599;5552.250446189793;5601.159826026088;5564.749586268627;5901.144334071536;5946.583739138262;6177.444030037318;6234.158388617524;6291.276503202144;6329.683515824237;6333.461655759266;6366.904496432558;6389.4568961638315;5561.721913904288;6580.0787889695;6581.407673309377;6592.472602191813;6632.204135503722;6232.781852635136;6254.249490061131;6273.215848018997;6282.905756413657;6318.368163183737;5614.887702246014;5666.886662266732;6268.811935641938;6389.6805159741825;6668.699908504129;5677.347105082886;6104.6874921892;6306.873862522724;6369.5222858285815;6377.457916077093;6204.503639708812;6454.451371633464;6518.511111666644;6533.216014078341;6549.279579246279;6175.752969881154;6204;6349;6465.579737317059;6467.126937863328;5984.3417224105315;6216.471599913967;6432.935670643327;6437.130987316746;6456.289808121103;5153.201253805666;5193.480651934777;5209.453007434241;5235.39792923811;5258.027264955996;3914.784686842194;4616.788683213312;4652.95347046532;4668.346431499593;4716.905661886812;5965.612235204698;6004.969975150097;6018.789342373084;6025.9397406026255;6307.810765677037;6431.196100487439;6500.739970401226;6544.181977252843;6550.148480697479;6554.606723596599;6179.16581261533;6186.597871138361;6195.349488303754;6201.720922558496;6217.409346112112;5632.577556683265;5839;5854.2096816930025;5868.853278667982;5882.470577647947;6614;6677.69950352245;6712.496562757715;6728.529002969745;6747.966260168668;6512.45071436059;6579.243387010363;6584.901226481702;6589.308122647245;6593.142891424084;6000.69996500182;6780.898286525608;6815.454763618799;6819.727210911508;6828.897566536605;5245.868853278546;5540.390557038803;6047.848803779941;6189.690515474298;6318.589291696025;6545.967270163619;6553.082568440517;6555.3772391622715;6557.540972131905;6585.901211481736;5823.563232757476;6040.637561746309;6335.524835637253;6367.140436041261;6375.139356186855;5684.459976302244;6170.413810687977;6201.162843016135;6355.3326900674765;6383;5413.539849112712;6099.634021958696;6232.657203853832;6259.874802504016;6260.812175634738;5831.825045248649;6276.167271642275;6287.119803227643;6339.144215531029;6341.524385671185;5803;6197.690115494117;6223.906641400292;6236.594621349598;6262.592931459622 11:27:58 INFO - PROCESS | 2944 | |1;ext.html;731.5683746589511;797.7686470923433;823.2549542663887;938.2915898496634;939.1594522902008;37805;38432.231355372875;40268.597314026854;40304;40305.79082627526;38569.8071509642;38714;38738.41892371617;38873.41689874654;39023.414648780294;7760.107587627424;7818;9184.265258779315;9943.552540135712;10691.465426728671;10447.477626118702;10550.683479495627;10553.261271710984;10553.472326383688;10592.41741704206;10540.20948428867;10576.36541807494;10610.840837387444;10637;10638.521266543024;8985.326100542485;10515.78968420635;10558.947205264001;10570.260081794277;10591.205659575562;10349.948250258776;10386.948065259625;10393.220508461896;10396.948015259875;10407.959204079532;33958;34079.14802129942;34110.82944585286;34118.805841795416;34178;3304.9173770655684;3601.3877640801124;3651.8721844735574;3695.9445608315896;3728.2170744143764;24725.010999559996;24824;25606.359841003938;25684.357891052685;26413.3396665083;2970.5692674562283;3008.7592992560544;3147.323325485009;3223.6453990061073;3229.40256052631;348900;352831;353109.99999999744;353407;354203;35192.824035879654;43633.56366436359;43756.68729938107;44013.559864401584;44015;44135;44158.11683766307;44262.55737442584;44417.33373999393;44427.77786111049;13822.861771382359;13862.237576933163;13929.442822286996;13934.442622295197;13952.16287022782;11743.11926605508;11897.226680265756;11932.940335298268;11959.282443053444;11981.562212534378;9866.408015519091;11692.88307116935;11816.645500634995;11892.286462812259;11908.821367679491;5186.8329625833885;5365;5607.775688972477;5654.9151762723595;5708.80019199326;952.7379970508166;959.1080295325274;965.2663975378763;1014.3862962907482;1042.7862288230895;29565.55651665227;29772.553411698842;29895.8493409407;29989;30050.398992020037;1005.1908213887745;1054.0724162736742;1054.7995880782628;1065.504540388723;1083.7994970930404;14861.405543778128;14965.401383944738;15049.247537623076;15049.699006020037;15553.766693499607;495381;496210;496439;498611;500612;45096.77451612721;45290.094198115854;45382;47383.76308118437;48706.75646621744;1372.8215332006775;1410.6825964157983;1413.1168019987508;1413.8232720909887;1415.936282867294;22097.668534971668;26184.60723089117;26995;27082.458350833265;27106.593401099395;40673.38989915213;40871.77384678464;40998;41114.79442602887;41138.588614113476;416653;484664;485028;487405;489827.10172897816;29502.409951801274;29735;29792;30102.397952041276;30172;38667;39092.82721518359;39436.40845387379;39554.406683899186;39579.60420395759;26327.605085923344;28189.43621127607;28413.573796393484;29066.982655606953;29345.55981660234;28003.0198943036;28379.864805407553;28428.289292767848;28989.565156522247;29154;31993.360132796748;32199;32566.023019309458;33128;33325;28898.85550572234;28908.566371504025;29330.853345733136;29334.41331173407;29781.702182977893;20898.89550552298;22492.550148997256;23050;24241.03035878545;24612.01551937974 11:27:58 INFO - PROCESS | 2944 | |2;jquery.html;404.971651984361;407.8164825828378;417.27394333859087;434.0624251616508;447.7335985088873;17597.912010439963;19934.900325498387;20488.897555512238;21273.04271307791;21418.89290553549;19983.300584479544;20114.69827952582;20407.979601019964;20879.164833406685;21127.260545880898;6686.364795344447;6996;7028.859422811541;7056.1179852518435;7066.434685225196;6623;6869.896951545731;6888.690008949584;6920.75777347793;6973.232944376115;6645.6344901030425;6939.548929319603;6967.442604591621;6985.860282794341;6986.9301306987045;6873;7010.263922288188;7012.123484564429;7020.929790702079;7023.157221133446;6761.560498567577;6771.052052712625;6777.966110169467;6866.588004719682;6869.210040845317;22882.77117228823;26733.866330668418;27033.459330813275;27335.726642733516;27373.863130684418;2915.9271018224504;2924.7513961313316;2947.2189869684553;2998.9803466821168;3016.1776802851573;16342.673146537003;16480.42318518846;16492.587685307844;16523.421680241136;16772.909760865627;2021.3632705697805;2054.301537477265;2055.835533157344;2062.3709768520594;2108.68369744538;101625.9837401624;132628;136207.31896340553;140533;144588.55411445856;22676.65985010226;22924.218367733967;23026.65460018101;23223.883880580488;23266.53466930652;27845.86077069642;31396.37207255842;35792.64207357945;36110.458343124876;36115.458268126;10915.23593348458;11066.889331106586;11127.610533631447;11194.272372295774;11203.271787333797;8951.373403861668;8980.057094005166;9005.684801031932;9108.8633670495;9156.313276504156;8780.692675756318;10375.481225938673;10656.520456579472;10753.408562528979;10839.729006774895;4432.911341773147;4482.305242687388;4495.775211239425;4529.411176547028;4533.569310915524;947.516766449116;1100.2958106811648;1120.6525976947173;1125.099920063962;1160.5009845766233;23153.073877045066;23221.53556928852;23320.067197311917;23322.533549328917;23340.883295583415;1130.519529200103;1137.9146564007733;1154.630518234157;1158.5250047480492;1169.9707507312385;9262.703221548989;11958.58144964922;12148.392580370946;12354.876451235372;12372.938135309267;668250;715714;748174;749432;754591;129414.35292823476;135552;142075;165255.1737241306;170103;1237.9814302785464;1241.3855141704914;1303.2310936547483;1305.4386613755987;1306.9085164038427;19161.94609296502;20094.59810803805;20383.18467261293;20675.68986465174;20746.896265518575;48939.75530122327;54512.72743636256;63924.360756393704;66325.33674663192;66349.33650663431;92072.53963730407;92868;92907.53546232225;93763.24402837212;93917.5304123475;9260.768480788034;11502.654920352403;11532.135089868461;11707.590234341757;11746.29522228669;12467.87532124667;12568.245905245714;12991.480340786262;13182.934085329896;13223.3388330585;10514.737131571772;10827.13382929348;11186.937240962096;11224.15818813576;11477.598284060017;10568.894311057098;10777.568897244022;10944.233903626988;10944.671659850217;11129.220954533104;11574.30554166719;11929.761404772029;12008.879911200776;12046.156769026084;12129.45417456199;10811.513481893177;11178.552857885594;11615.128865334964;11999.100067494799;12197.817032744339;10947.507362168562;11000.22998390105;11056.613018544313;11148.609798657008;11250 11:27:58 INFO - PROCESS | 2944 | |3;mootools.html;1556.7197904377208;1572.1274692545633;1591.7771511988324;1591.9174961026504;1601.5595711179428;10586.41774702391;11062.280951738132;11171.832422513658;11172.217944743872;11396.373199474026;10960.232783705143;11157.330560166396;11161.939615736492;11164.162687798407;11176.664700059011;2382.9046838126455;2415.830891837572;2434.6834911461483;2436.0742917691255;2438.7317395086525;2113.714648522453;2319.9652005219937;2375.714914210297;2410.674558934548;2412.1436889904094;2311.84972976756;2391.940201494968;2395.3652282145244;2401.7838394544488;2403.531311394279;2189.1681161158817;2320.234322673514;2360.9645855312183;2374.133441293924;2374.6675465434855;2212.5353675727956;2353.176388264094;2361.5276944611146;2369.680093187415;2376.489054853215;9331.253499720005;10977.451127443675;11118.221724479208;11175.273607215506;11177.49701263445;4900.779464924087;4997.675151115166;5705.172749951275;5783.5084017858535;5926.170336152942;3594.676479116892;3655.4882316475714;3659.2132691471197;3685.649863262986;3706.1475860551955;1206.1737709668905;1213.1083653514659;1243.9564615238514;1263.5198624522714;1269.574692478021;12157.66265710771;13716.794248086288;13902.582922512342;14709.558713238514;15217.391304347813;4612.723236605814;4650.51169627191;4797.160496913027;4803.375561177095;4804.927926081112;11796.351200684045;12013.399330033464;12031.398430078461;12040;12131.45408456604;5808.157817116579;5868.853278668067;5874.265716785402;5875.676837773878;5883.17635531026;5122.206058061007;5157.63896527247;5213.6350455467755;5219.060569097599;5262.2632831403635;3825.292320920583;3905.4922860028014;3918.6676529979945;3949.407588861636;3995.62041606047;914.8947870994788;1038.9740256493649;1043.9582416703247;1050.81610718124;1053.6944286157081;807.4428644235459;910.3127139010005;914.6890057380523;917.6191880369572;953.4041224234853;6276.497880169667;6755.324467553205;6775.559588626724;6780.457563394916;6848.417884479775;995.348047029197;996.6262651505685;1004.8995100489892;1011.2264117949775;1035.984460233097;4297.656187504992;4316.719413238192;4361.890952726208;4446.866594002185;4471.530489298641;13283.800742988862;13833.584992450242;13933.512327068504;13940.860591393955;13961.162330260217;13451.92384609229;13679.384427700777;13836.962227832953;13840.169589824643;13865.722685546232;8714.389992700577;10197.286189966631;10259.846102308471;10296.639617613348;10303.639372621923;5559.555235581063;5926.644401335933;6566.242178285036;6849.24658287578;6949.7567585134275;12112.45493952792;12118.636440906786;12123.15137940371;12134.635960921187;12196;10205.38767673942;12407.565735199225;12418.565350212699;12436.37818109109;12654.304013279358;8407.831843363221;8534.591792354193;8551.828963420572;8877.689280875138;8885;10556.419396933243;10651;10670;10677.359358438207;10678.73303167427;8834.558272086499;8899.911000889908;8950.731478055668;9012.369134160808;9013.684521041732;7551.962240188764;8772.692955746517;8830.558472076498;8855.690050848189;8858.114188581349;7850.921490785019;7858;7883.2905038546805;7917.881231781413;7968.721094761888;5915.231019967377;5957.076653118774;6019.0068638675875;6036.63780173191;6076.270847498328;4801.4023898069845;5953.255843019622;6112.510999120149;6193;6242.375762423721 11:27:58 INFO - PROCESS | 2944 | |4;prototype.html;367.77198137154966;371.80480247869866;381.9885403437897;383.9117003089289;387.7750904475404;4319.855238361836;4671.439427268724;5130.127503461233;5148.841510660104;5338.973305133479;4466.754328511931;4963.578095861856;5033.622478314122;5089.2620570017325;5142.637201141701;3839.8129552471523;4212.789360531969;4240.469941257343;4245.936310955338;4274.978625106885;4269.6370808481315;4282.807273672676;4295.935560966588;4321.697481176303;4326.805293761772;3956.3669812829953;4182.811773470201;4241.5122260939925;4270.807813648377;4277.165952639237;4093.6315731584004;4187.623113919732;4208.642265407444;4254.042840360925;4298.505671847746;3186.3308705171944;3566.3402270580027;3898;3900.609939006105;3948.8153553933994;4208.894777630584;4999.850004499871;5011.649184557083;5043.62172837035;5043.722595257296;4737.620990320766;4836.524859917724;4877.951220487821;4959.8264060758065;5033.572146367563;4588.678792484528;5424.810131645373;5539.972300138514;5555.194496797942;5598.776048958037;3917.491765670209;3931.2334094851517;3960.742551734128;4133.904515303447;4305.246581848195;5069.315642388267;5138.460461651547;5588.189712491666;5769.798057067983;5805.071188609802;4100.302948498763;4323.557190187534;4436.689431739812;4450.261780104727;4528.841490547815;3677.8528858845348;4061.005053761847;4249.787510624456;4343.543927887589;4581.656375771764;4269.850555230552;4626.537346265414;4889.339939108246;4910.754462276872;4935.531124543163;4870.245112007645;5228.738563071831;5266.605004624668;5500.862478437991;5564.8330550083565;1508.5549762819994;1591.368846931904;1701.3534856754354;1714.545645403944;1734.8661146789268;1206.9517219311128;1323.6823162440944;1328.2827273272326;1412.1174266083697;1412.8233970753656;4697.342372067913;4751.691140075884;4971.6271279654175;5001.574866136347;5138.948610513922;5737.540996720252;5740.540756739534;5741.425857414309;5787.479126878601;5791.536677065825;4492.9550704493195;4580.3968610985885;5126.410462796826;5132.769025393866;5191.819435836957;2627.973720262773;2854.4576530459144;2905.186547766629;2967.3827763868744;2968.3172870239964;5341.7062061586985;5360.624756267024;5535.564351685246;5966.164736936832;5986.73059712314;5087.592992560586;5508.889822203613;5649.655393011936;5896.410358964069;5908.911366329508;5561.416051314553;5901.514848715007;5908.291005079332;5912.852178695481;5915.994280972245;5009.096543313518;5291.814786482455;5373.811916583058;5628.2833735711;5720.942790572041;4964.939550086674;5467.976848566056;5494.395616482104;5629.380768115422;5703.258576385043;5109.335786347751;5308.654937429054;5335.532728499694;5472.972635136799;5644.463775941279;4408.823647054211;4615.353850460871;4638.373819534455;4968.304437378698;4999.300097986357;4950.871125415962;5165.92251116226;5477.67133971963;5487.052096505683;5647.0964645656495;4713.929291060568;5200.6879587224885;5203.375594928632;5498.862528436661;5501.697406642673;4487.371767952554;5332.893342133213;5577;5611.859703507445;5622.746976385991;4442.933355999728;5327.9467205329;5328.600354973315;5344.893102137858;5381.704006279536;4796.664233503621;5132.738942583881;5325.414204437587;5484.095124304605;5623.297087864017;4186.413902053776;4703.3650457189215;5319.255304257485;5421.268128802719;5447.155690867922 11:27:58 INFO - PROCESS | 2944 | |5;yui.html;728.8651599454098;770.7014276846679;779.9844003119938;828.1511450762963;843.5782108945527;15114.669385348112;16245.756313655305;16714.997100173998;16754.91622541887;16764.83235167648;15391.769123463157;15435.69128617427;15569.221538923066;15582.376704931788;15600.375984960616;923.3905622289291;944;948.6300342866288;949.3259785552266;949.9620015199383;915.057490784493;940.6190492850372;941.9858702119474;943.2077055273569;943.5895385507292;991.5587563534231;1008.6318493749765;1008.8234558952154;1016.6086056868085;1017.9083882450578;848.944818586796;1004.7739258666815;1008.9545970431349;1009.0666133826181;1010.3988127064422;742.936850367714;749.6401727170934;750.114864459937;750.1186106325047;750.6884642873201;16400.671986560323;16886.746698799405;16923.91538042302;16978.575535611584;17211.827881721147;12735.872641273654;13055.347232638425;13085.86914130856;13168.144070635473;13236.568135801246;11459.31244125355;11735.882641173564;11747.471363788565;12044.39778011096;12108.576199832964;3672.06362377592;3682.502862113607;3711.0722319420147;3729.160938788778;3867.903302417434;59225;67632.64734705279;102930;110282.44858775735;111441;11109.333439993627;11123.499442524944;11141.665750027512;11189.832152517718;11325.71685707864;22188.667169992463;22300.44248893758;22363.329100127023;22444.55110897773;22546;7561.886571701319;7958.801029974181;8036.196380362034;8116.918830811616;8144.633491492897;4341.630961368256;4410.757408342572;4434.600885920283;4458.665600079942;4609.838655647103;3458.8962331130106;3503.649635036476;3516.982415087908;3524.3127590120193;3541.6812486876306;633.4678869749432;644.1207751419279;647.3591144766741;662.3906006474056;666.0907860770014;657.9539432239793;675.5507587454288;688.5317983770983;692.5775277080977;727.2727272727273;9744.512774361254;10634.946825265979;10636.787264254672;10774.515146818412;10878.782424351626;697.1390332938817;701.9227884932653;707.2927072927073;723.450177864816;729.702665992492;4987;5117.232415137759;5117.795288188505;5120.771014956384;5146.150885103991;70300;76423;94844.0515594849;96096;108801.19195828108;81505.59247203726;82446;83771.58114209512;85373.57313213394;96411.51794241124;54126;71547.2845271551;74037.6298118506;74618.99999999891;76295;10683.94658026705;11120.555177792798;11193.27243729155;11341.943290283496;11342.20604557706;47728;48878.75560622174;49263;50209;50975;66900;68088.65955670387;72363.19092022741;72496;73339;13043.673908152372;13120.485899523506;13157.210567365988;13354.998375122099;13863.4454621814;15052.397904083715;15103;15330.540083797503;15428.997115187476;15932.283047263132;13800.792988104986;13823.792643110562;14388.35252413623;14400.567982960529;14645.487407941097;13512.79730804019;13960.441582336593;14076.085054471427;14087.647808804863;14177.858221417653;13239.40422681001;13429.66425839362;13775.448982040607;13903.652408689864;14090.577282681536;9478.620855165991;9994.400335979864;10086;10102.848457273294;10346.844797327896;11112.499937502671;11185.660122674939;11261.66215013551;11273.154513411691;11332.036776874058 11:27:58 INFO - PROCESS | 2944 | __end_tp_report 11:27:58 INFO - PROCESS | 2944 | __start_cc_report 11:27:58 INFO - PROCESS | 2944 | _x_x_mozilla_cycle_collect,415 11:27:58 INFO - PROCESS | 2944 | __end_cc_report 11:27:58 INFO - PROCESS | 2944 | __startTimestamp1491848878506__endTimestamp 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | ------- Summary: start ------- 11:27:58 INFO - PROCESS | 2944 | Number of tests: 6 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#0] dojo.html Cycles:165 Average:5967.26 Median:6189.69 stddev:682.63 (11.0%) stddev-sans-first:643.06 11:27:58 INFO - PROCESS | 2944 | Values: 2973.5 3465.4 3487.4 3491.0 3511.9 6121.1 6129.5 6153.0 6163.7 6175.8 6113.3 6129.6 6174.5 6196.7 6219.0 4657.7 5320.9 5520.5 5551.2 5614.1 5567.8 5579.2 5598.7 5613.5 5631.9 5066.2 5484.6 5581.5 5583.4 5606.9 5498.9 5514.8 5528.6 5555.6 5563.4 5400.6 5521.6 5530.6 5552.3 5601.2 5564.7 5901.1 5946.6 6177.4 6234.2 6291.3 6329.7 6333.5 6366.9 6389.5 5561.7 6580.1 6581.4 6592.5 6632.2 6232.8 6254.2 6273.2 6282.9 6318.4 5614.9 5666.9 6268.8 6389.7 6668.7 5677.3 6104.7 6306.9 6369.5 6377.5 6204.5 6454.5 6518.5 6533.2 6549.3 6175.8 6204.0 6349.0 6465.6 6467.1 5984.3 6216.5 6432.9 6437.1 6456.3 5153.2 5193.5 5209.5 5235.4 5258.0 3914.8 4616.8 4653.0 4668.3 4716.9 5965.6 6005.0 6018.8 6025.9 6307.8 6431.2 6500.7 6544.2 6550.1 6554.6 6179.2 6186.6 6195.3 6201.7 6217.4 5632.6 5839.0 5854.2 5868.9 5882.5 6614.0 6677.7 6712.5 6728.5 6748.0 6512.5 6579.2 6584.9 6589.3 6593.1 6000.7 6780.9 6815.5 6819.7 6828.9 5245.9 5540.4 6047.8 6189.7 6318.6 6546.0 6553.1 6555.4 6557.5 6585.9 5823.6 6040.6 6335.5 6367.1 6375.1 5684.5 6170.4 6201.2 6355.3 6383.0 5413.5 6099.6 6232.7 6259.9 6260.8 5831.8 6276.2 6287.1 6339.1 6341.5 5803.0 6197.7 6223.9 6236.6 6262.6 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#1] ext.html Cycles:180 Average:56125.44 Median:16555.58 stddev:118693.03 (716.9%) stddev-sans-first:118953.13 11:27:58 INFO - PROCESS | 2944 | Values: 731.6 797.8 823.3 938.3 939.2 37805.0 38432.2 40268.6 40304.0 40305.8 38569.8 38714.0 38738.4 38873.4 39023.4 7760.1 7818.0 9184.3 9943.6 10691.5 10447.5 10550.7 10553.3 10553.5 10592.4 10540.2 10576.4 10610.8 10637.0 10638.5 8985.3 10515.8 10558.9 10570.3 10591.2 10349.9 10386.9 10393.2 10396.9 10408.0 33958.0 34079.1 34110.8 34118.8 34178.0 3304.9 3601.4 3651.9 3695.9 3728.2 24725.0 24824.0 25606.4 25684.4 26413.3 2970.6 3008.8 3147.3 3223.6 3229.4 348900.0 352831.0 353110.0 353407.0 354203.0 35192.8 43633.6 43756.7 44013.6 44015.0 44135.0 44158.1 44262.6 44417.3 44427.8 13822.9 13862.2 13929.4 13934.4 13952.2 11743.1 11897.2 11932.9 11959.3 11981.6 9866.4 11692.9 11816.6 11892.3 11908.8 5186.8 5365.0 5607.8 5654.9 5708.8 952.7 959.1 965.3 1014.4 1042.8 29565.6 29772.6 29895.8 29989.0 30050.4 1005.2 1054.1 1054.8 1065.5 1083.8 14861.4 14965.4 15049.2 15049.7 15553.8 495381.0 496210.0 496439.0 498611.0 500612.0 45096.8 45290.1 45382.0 47383.8 48706.8 1372.8 1410.7 1413.1 1413.8 1415.9 22097.7 26184.6 26995.0 27082.5 27106.6 40673.4 40871.8 40998.0 41114.8 41138.6 416653.0 484664.0 485028.0 487405.0 489827.1 29502.4 29735.0 29792.0 30102.4 30172.0 38667.0 39092.8 39436.4 39554.4 39579.6 26327.6 28189.4 28413.6 29067.0 29345.6 28003.0 28379.9 28428.3 28989.6 29154.0 31993.4 32199.0 32566.0 33128.0 33325.0 28898.9 28908.6 29330.9 29334.4 29781.7 20898.9 22492.6 23050.0 24241.0 24612.0 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#2] jquery.html Cycles:180 Average:42052.88 Median:20813.03 stddev:121026.37 (581.5%) stddev-sans-first:121325.48 11:27:58 INFO - PROCESS | 2944 | Values: 405.0 407.8 417.3 434.1 447.7 17597.9 19934.9 20488.9 21273.0 21418.9 19983.3 20114.7 20408.0 20879.2 21127.3 6686.4 6996.0 7028.9 7056.1 7066.4 6623.0 6869.9 6888.7 6920.8 6973.2 6645.6 6939.5 6967.4 6985.9 6986.9 6873.0 7010.3 7012.1 7020.9 7023.2 6761.6 6771.1 6778.0 6866.6 6869.2 22882.8 26733.9 27033.5 27335.7 27373.9 2915.9 2924.8 2947.2 2999.0 3016.2 16342.7 16480.4 16492.6 16523.4 16772.9 2021.4 2054.3 2055.8 2062.4 2108.7 101626.0 132628.0 136207.3 140533.0 144588.6 22676.7 22924.2 23026.7 23223.9 23266.5 27845.9 31396.4 35792.6 36110.5 36115.5 10915.2 11066.9 11127.6 11194.3 11203.3 8951.4 8980.1 9005.7 9108.9 9156.3 8780.7 10375.5 10656.5 10753.4 10839.7 4432.9 4482.3 4495.8 4529.4 4533.6 947.5 1100.3 1120.7 1125.1 1160.5 23153.1 23221.5 23320.1 23322.5 23340.9 1130.5 1137.9 1154.6 1158.5 1170.0 9262.7 11958.6 12148.4 12354.9 12372.9 668250.0 715714.0 748174.0 749432.0 754591.0 129414.4 135552.0 142075.0 165255.2 170103.0 1238.0 1241.4 1303.2 1305.4 1306.9 19161.9 20094.6 20383.2 20675.7 20746.9 48939.8 54512.7 63924.4 66325.3 66349.3 92072.5 92868.0 92907.5 93763.2 93917.5 9260.8 11502.7 11532.1 11707.6 11746.3 12467.9 12568.2 12991.5 13182.9 13223.3 10514.7 10827.1 11186.9 11224.2 11477.6 10568.9 10777.6 10944.2 10944.7 11129.2 11574.3 11929.8 12008.9 12046.2 12129.5 10811.5 11178.6 11615.1 11999.1 12197.8 10947.5 11000.2 11056.6 11148.6 11250.0 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#3] mootools.html Cycles:180 Average:6692.28 Median:2413.99 stddev:4155.27 (172.1%) stddev-sans-first:4149.01 11:27:58 INFO - PROCESS | 2944 | Values: 1556.7 1572.1 1591.8 1591.9 1601.6 10586.4 11062.3 11171.8 11172.2 11396.4 10960.2 11157.3 11161.9 11164.2 11176.7 2382.9 2415.8 2434.7 2436.1 2438.7 2113.7 2320.0 2375.7 2410.7 2412.1 2311.8 2391.9 2395.4 2401.8 2403.5 2189.2 2320.2 2361.0 2374.1 2374.7 2212.5 2353.2 2361.5 2369.7 2376.5 9331.3 10977.5 11118.2 11175.3 11177.5 4900.8 4997.7 5705.2 5783.5 5926.2 3594.7 3655.5 3659.2 3685.6 3706.1 1206.2 1213.1 1244.0 1263.5 1269.6 12157.7 13716.8 13902.6 14709.6 15217.4 4612.7 4650.5 4797.2 4803.4 4804.9 11796.4 12013.4 12031.4 12040.0 12131.5 5808.2 5868.9 5874.3 5875.7 5883.2 5122.2 5157.6 5213.6 5219.1 5262.3 3825.3 3905.5 3918.7 3949.4 3995.6 914.9 1039.0 1044.0 1050.8 1053.7 807.4 910.3 914.7 917.6 953.4 6276.5 6755.3 6775.6 6780.5 6848.4 995.3 996.6 1004.9 1011.2 1036.0 4297.7 4316.7 4361.9 4446.9 4471.5 13283.8 13833.6 13933.5 13940.9 13961.2 13451.9 13679.4 13837.0 13840.2 13865.7 8714.4 10197.3 10259.8 10296.6 10303.6 5559.6 5926.6 6566.2 6849.2 6949.8 12112.5 12118.6 12123.2 12134.6 12196.0 10205.4 12407.6 12418.6 12436.4 12654.3 8407.8 8534.6 8551.8 8877.7 8885.0 10556.4 10651.0 10670.0 10677.4 10678.7 8834.6 8899.9 8950.7 9012.4 9013.7 7552.0 8772.7 8830.6 8855.7 8858.1 7850.9 7858.0 7883.3 7917.9 7968.7 5915.2 5957.1 6019.0 6036.6 6076.3 4801.4 5953.3 6112.5 6193.0 6242.4 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#4] prototype.html Cycles:180 Average:4577.73 Median:4966.62 stddev:1252.43 (25.2%) stddev-sans-first:1215.43 11:27:58 INFO - PROCESS | 2944 | Values: 367.8 371.8 382.0 383.9 387.8 4319.9 4671.4 5130.1 5148.8 5339.0 4466.8 4963.6 5033.6 5089.3 5142.6 3839.8 4212.8 4240.5 4245.9 4275.0 4269.6 4282.8 4295.9 4321.7 4326.8 3956.4 4182.8 4241.5 4270.8 4277.2 4093.6 4187.6 4208.6 4254.0 4298.5 3186.3 3566.3 3898.0 3900.6 3948.8 4208.9 4999.9 5011.6 5043.6 5043.7 4737.6 4836.5 4878.0 4959.8 5033.6 4588.7 5424.8 5540.0 5555.2 5598.8 3917.5 3931.2 3960.7 4133.9 4305.2 5069.3 5138.5 5588.2 5769.8 5805.1 4100.3 4323.6 4436.7 4450.3 4528.8 3677.9 4061.0 4249.8 4343.5 4581.7 4269.9 4626.5 4889.3 4910.8 4935.5 4870.2 5228.7 5266.6 5500.9 5564.8 1508.6 1591.4 1701.4 1714.5 1734.9 1207.0 1323.7 1328.3 1412.1 1412.8 4697.3 4751.7 4971.6 5001.6 5138.9 5737.5 5740.5 5741.4 5787.5 5791.5 4493.0 4580.4 5126.4 5132.8 5191.8 2628.0 2854.5 2905.2 2967.4 2968.3 5341.7 5360.6 5535.6 5966.2 5986.7 5087.6 5508.9 5649.7 5896.4 5908.9 5561.4 5901.5 5908.3 5912.9 5916.0 5009.1 5291.8 5373.8 5628.3 5720.9 4964.9 5468.0 5494.4 5629.4 5703.3 5109.3 5308.7 5335.5 5473.0 5644.5 4408.8 4615.4 4638.4 4968.3 4999.3 4950.9 5165.9 5477.7 5487.1 5647.1 4713.9 5200.7 5203.4 5498.9 5501.7 4487.4 5332.9 5577.0 5611.9 5622.7 4442.9 5327.9 5328.6 5344.9 5381.7 4796.7 5132.7 5325.4 5484.1 5623.3 4186.4 4703.4 5319.3 5421.3 5447.2 11:27:58 INFO - PROCESS | 2944 | 11:27:58 INFO - PROCESS | 2944 | [#5] yui.html Cycles:180 Average:19679.48 Median:3510.32 stddev:26970.01 (768.3%) stddev-sans-first:27008.13 11:27:58 INFO - PROCESS | 2944 | Values: 728.9 770.7 780.0 828.2 843.6 15114.7 16245.8 16715.0 16754.9 16764.8 15391.8 15435.7 15569.2 15582.4 15600.4 923.4 944.0 948.6 949.3 950.0 915.1 940.6 942.0 943.2 943.6 991.6 1008.6 1008.8 1016.6 1017.9 848.9 1004.8 1009.0 1009.1 1010.4 742.9 749.6 750.1 750.1 750.7 16400.7 16886.7 16923.9 16978.6 17211.8 12735.9 13055.3 13085.9 13168.1 13236.6 11459.3 11735.9 11747.5 12044.4 12108.6 3672.1 3682.5 3711.1 3729.2 3867.9 59225.0 67632.6 102930.0 110282.4 111441.0 11109.3 11123.5 11141.7 11189.8 11325.7 22188.7 22300.4 22363.3 22444.6 22546.0 7561.9 7958.8 8036.2 8116.9 8144.6 4341.6 4410.8 4434.6 4458.7 4609.8 3458.9 3503.6 3517.0 3524.3 3541.7 633.5 644.1 647.4 662.4 666.1 658.0 675.6 688.5 692.6 727.3 9744.5 10634.9 10636.8 10774.5 10878.8 697.1 701.9 707.3 723.5 729.7 4987.0 5117.2 5117.8 5120.8 5146.2 70300.0 76423.0 94844.1 96096.0 108801.2 81505.6 82446.0 83771.6 85373.6 96411.5 54126.0 71547.3 74037.6 74619.0 76295.0 10683.9 11120.6 11193.3 11341.9 11342.2 47728.0 48878.8 49263.0 50209.0 50975.0 66900.0 68088.7 72363.2 72496.0 73339.0 13043.7 13120.5 13157.2 13355.0 13863.4 15052.4 15103.0 15330.5 15429.0 15932.3 13800.8 13823.8 14388.4 14400.6 14645.5 13512.8 13960.4 14076.1 14087.6 14177.9 13239.4 13429.7 13775.4 13903.7 14090.6 9478.6 9994.4 10086.0 10102.8 10346.8 11112.5 11185.7 11261.7 11273.2 11332.0 11:27:58 INFO - PROCESS | 2944 | -------- Summary: end -------- 11:27:58 INFO - PROCESS | 2944 | 11:27:59 INFO - TEST-INFO | 2944: exit 0 11:27:59 INFO - TEST-OK | dromaeo_css | took 1117507ms 11:27:59 INFO - TEST-START | kraken 11:27:59 INFO - operating with platform_type : mac_ 11:27:59 INFO - Initialising browser for kraken test... 11:27:59 INFO - TEST-INFO | started process 2948 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpJ0zXsj/profile http://localhost:62539/getInfo.html) 11:28:02 INFO - PROCESS | 2948 | 2017-04-10 11:28:02.214 plugin-container[2949:48940] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:28:02 INFO - PROCESS | 2948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:02 INFO - PROCESS | 2948 | 2017-04-10 11:28:02.217 plugin-container[2949:48940] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 11:28:02 INFO - PROCESS | 2948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:02 INFO - PROCESS | 2948 | 2017-04-10 11:28:02.217 plugin-container[2949:48940] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:28:02 INFO - PROCESS | 2948 | __metrics Screen width/height:1600/1200 11:28:02 INFO - PROCESS | 2948 | colorDepth:24 11:28:02 INFO - PROCESS | 2948 | Browser inner width/height: 1024/689 11:28:02 INFO - PROCESS | 2948 | __metrics 11:28:02 ERROR - PROCESS | 2948 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 11:28:03 INFO - TEST-INFO | 2948: exit 0 11:28:03 INFO - Browser initialized. 11:28:03 INFO - Running cycle 1/1 for kraken test... 11:28:03 INFO - TEST-INFO | started process 2950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/T/tmpJ0zXsj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:28:05 INFO - PROCESS | 2950 | 2017-04-10 11:28:05.690 plugin-container[2951:49065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:28:05 INFO - PROCESS | 2950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:05 INFO - PROCESS | 2950 | 2017-04-10 11:28:05.691 plugin-container[2951:49065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 11:28:05 INFO - PROCESS | 2950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:28:05 INFO - PROCESS | 2950 | 2017-04-10 11:28:05.691 plugin-container[2951:49065] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:28:15 INFO - PROCESS | 2950 | RSS: Main: 221093888 11:28:15 INFO - PROCESS | 2950 | 11:28:18 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62539/tests/kraken/driver.html?testName=audio-beat-detection) 11:28:19 INFO - PROCESS | 2950 | RSS: Main: 235823104 11:28:19 INFO - PROCESS | 2950 | 11:28:22 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62539/tests/kraken/driver.html?testName=audio-dft) 11:28:23 INFO - PROCESS | 2950 | RSS: Main: 243556352 11:28:23 INFO - PROCESS | 2950 | 11:28:26 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62539/tests/kraken/driver.html?testName=audio-fft) 11:28:26 INFO - PROCESS | 2950 | RSS: Main: 247078912 11:28:26 INFO - PROCESS | 2950 | 11:28:28 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62539/tests/kraken/driver.html?testName=audio-oscillator) 11:28:29 INFO - PROCESS | 2950 | RSS: Main: 247545856 11:28:29 INFO - PROCESS | 2950 | 11:28:31 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62539/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:28:32 INFO - PROCESS | 2950 | RSS: Main: 249004032 11:28:32 INFO - PROCESS | 2950 | 11:28:35 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62539/tests/kraken/driver.html?testName=imaging-darkroom) 11:28:35 INFO - PROCESS | 2950 | RSS: Main: 252690432 11:28:35 INFO - PROCESS | 2950 | 11:28:39 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62539/tests/kraken/driver.html?testName=imaging-desaturate) 11:28:39 INFO - PROCESS | 2950 | RSS: Main: 252145664 11:28:39 INFO - PROCESS | 2950 | 11:28:42 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62539/tests/kraken/driver.html?testName=json-parse-financial) 11:28:43 INFO - PROCESS | 2950 | RSS: Main: 252284928 11:28:43 INFO - PROCESS | 2950 | 11:28:45 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62539/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:28:45 INFO - PROCESS | 2950 | RSS: Main: 252166144 11:28:45 INFO - PROCESS | 2950 | 11:28:47 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:28:48 INFO - PROCESS | 2950 | RSS: Main: 251973632 11:28:48 INFO - PROCESS | 2950 | 11:28:50 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:28:51 INFO - PROCESS | 2950 | RSS: Main: 251957248 11:28:51 INFO - PROCESS | 2950 | 11:28:54 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:28:55 INFO - PROCESS | 2950 | RSS: Main: 252608512 11:28:55 INFO - PROCESS | 2950 | 11:28:57 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:28:58 INFO - PROCESS | 2950 | RSS: Main: 252366848 11:28:58 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:62539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62539/tests/kraken/driver.html?testName=ai-astar) 11:29:00 INFO - PROCESS | 2950 | RSS: Main: 254308352 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | __start_tp_report 11:29:00 INFO - PROCESS | 2950 | _x_x_mozilla_page_load 11:29:00 INFO - PROCESS | 2950 | _x_x_mozilla_page_load_details 11:29:00 INFO - PROCESS | 2950 | |i|pagename|runs| 11:29:00 INFO - PROCESS | 2950 | |0;ai-astar;127;175;119;120;119;135;175;158;120;120 11:29:00 INFO - PROCESS | 2950 | |1;audio-beat-detection;147;103;111;174;138;120;118;120;158;138 11:29:00 INFO - PROCESS | 2950 | |2;audio-dft;203;198;200;198;197;203;200;198;203;198 11:29:00 INFO - PROCESS | 2950 | |3;audio-fft;85;62;61;88;61;60;61;61;74;59 11:29:00 INFO - PROCESS | 2950 | |4;audio-oscillator;93;71;103;86;95;100;114;72;71;84 11:29:00 INFO - PROCESS | 2950 | |5;imaging-gaussian-blur;123;96;91;100;93;94;92;93;93;93 11:29:00 INFO - PROCESS | 2950 | |6;imaging-darkroom;96;100;105;99;100;118;110;100;100;98 11:29:00 INFO - PROCESS | 2950 | |7;imaging-desaturate;90;93;90;115;111;92;93;84;91;94 11:29:00 INFO - PROCESS | 2950 | |8;json-parse-financial;66;67;68;67;83;103;104;106;104;106 11:29:00 INFO - PROCESS | 2950 | |9;json-stringify-tinderbox;56;66;59;70;69;63;58;59;59;62 11:29:00 INFO - PROCESS | 2950 | |10;stanford-crypto-aes;83;85;88;85;86;86;89;85;87;85 11:29:00 INFO - PROCESS | 2950 | |11;stanford-crypto-ccm;144;150;168;148;163;145;139;157;202;155 11:29:00 INFO - PROCESS | 2950 | |12;stanford-crypto-pbkdf2;171;173;171;171;171;169;170;165;164;166 11:29:00 INFO - PROCESS | 2950 | |13;stanford-crypto-sha256-iterative;68;66;67;66;68;67;66;64;66;66 11:29:00 INFO - PROCESS | 2950 | __end_tp_report 11:29:00 INFO - PROCESS | 2950 | __start_cc_report 11:29:00 INFO - PROCESS | 2950 | _x_x_mozilla_cycle_collect,1019 11:29:00 INFO - PROCESS | 2950 | __end_cc_report 11:29:00 INFO - PROCESS | 2950 | __startTimestamp1491848940413__endTimestamp 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | ------- Summary: start ------- 11:29:00 INFO - PROCESS | 2950 | Number of tests: 14 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#0] ai-astar Cycles:10 Average:136.80 Median:131.00 stddev:23.45 (17.9%) stddev-sans-first:24.60 11:29:00 INFO - PROCESS | 2950 | Values: 127.0 175.0 119.0 120.0 119.0 135.0 175.0 158.0 120.0 120.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:138.00 stddev:22.36 (16.2%) stddev-sans-first:23.11 11:29:00 INFO - PROCESS | 2950 | Values: 147.0 103.0 111.0 174.0 138.0 120.0 118.0 120.0 158.0 138.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#2] audio-dft Cycles:10 Average:199.80 Median:200.00 stddev:2.39 (1.2%) stddev-sans-first:2.24 11:29:00 INFO - PROCESS | 2950 | Values: 203.0 198.0 200.0 198.0 197.0 203.0 200.0 198.0 203.0 198.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#3] audio-fft Cycles:10 Average:67.20 Median:61.50 stddev:11.03 (17.9%) stddev-sans-first:9.64 11:29:00 INFO - PROCESS | 2950 | Values: 85.0 62.0 61.0 88.0 61.0 60.0 61.0 61.0 74.0 59.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:78.00 stddev:14.78 (18.9%) stddev-sans-first:15.60 11:29:00 INFO - PROCESS | 2950 | Values: 93.0 71.0 103.0 86.0 95.0 100.0 114.0 72.0 71.0 84.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:93.00 stddev:9.54 (10.3%) stddev-sans-first:2.67 11:29:00 INFO - PROCESS | 2950 | Values: 123.0 96.0 91.0 100.0 93.0 94.0 92.0 93.0 93.0 93.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:114.00 stddev:6.69 (5.9%) stddev-sans-first:6.65 11:29:00 INFO - PROCESS | 2950 | Values: 96.0 100.0 105.0 99.0 100.0 118.0 110.0 100.0 100.0 98.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:91.50 stddev:9.78 (10.7%) stddev-sans-first:10.18 11:29:00 INFO - PROCESS | 2950 | Values: 90.0 93.0 90.0 115.0 111.0 92.0 93.0 84.0 91.0 94.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#8] json-parse-financial Cycles:10 Average:87.40 Median:66.50 stddev:18.77 (28.2%) stddev-sans-first:18.25 11:29:00 INFO - PROCESS | 2950 | Values: 66.0 67.0 68.0 67.0 83.0 103.0 104.0 106.0 104.0 106.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.50 stddev:4.82 (7.7%) stddev-sans-first:4.58 11:29:00 INFO - PROCESS | 2950 | Values: 56.0 66.0 59.0 70.0 69.0 63.0 58.0 59.0 59.0 62.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:1.73 (2.0%) stddev-sans-first:1.48 11:29:00 INFO - PROCESS | 2950 | Values: 83.0 85.0 88.0 85.0 86.0 86.0 89.0 85.0 87.0 85.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#11] stanford-crypto-ccm Cycles:10 Average:157.10 Median:156.00 stddev:18.11 (11.6%) stddev-sans-first:18.58 11:29:00 INFO - PROCESS | 2950 | Values: 144.0 150.0 168.0 148.0 163.0 145.0 139.0 157.0 202.0 155.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:171.00 stddev:3.03 (1.8%) stddev-sans-first:3.14 11:29:00 INFO - PROCESS | 2950 | Values: 171.0 173.0 171.0 171.0 171.0 169.0 170.0 165.0 164.0 166.0 11:29:00 INFO - PROCESS | 2950 | 11:29:00 INFO - PROCESS | 2950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:1.17 (1.8%) stddev-sans-first:1.09 11:29:00 INFO - PROCESS | 2950 | Values: 68.0 66.0 67.0 66.0 68.0 67.0 66.0 64.0 66.0 66.0 11:29:00 INFO - PROCESS | 2950 | -------- Summary: end -------- 11:29:00 INFO - PROCESS | 2950 | 11:29:01 INFO - TEST-INFO | 2950: exit 0 11:29:01 INFO - TEST-OK | kraken | took 62131ms 11:29:01 INFO - SUITE-END | took 1179s 11:29:01 INFO - Completed test suite (00:19:39) 11:29:01 INFO - javascript benchmark 11:29:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7711.814132608409, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2973.4647763402586, 3465.3589086019088, 3487.4245749451343, 3490.9650903490956, 3511.9122021949447, 6121.112438696387, 6129.509639228862, 6153.046277826933, 6163.691815409234, 6175.75296988121, 6113.2664080310315, 6129.570930034899, 6174.536909731765, 6196.659183744903, 6218.968905155479, 4657.743824089674, 5320.866978325554, 5520.530754885839, 5551.2228288039705, 5614.129809879455, 5567.832965011056, 5579.163125531162, 5598.676549232064, 5613.46672066153, 5631.943680563203, 5066.240063990394, 5484.61607687462, 5581.5255703265275, 5583.35791383992, 5606.943930560683, 5498.945010549884, 5514.834554963358, 5528.585356098309, 5555.638883472581, 5563.443655634444, 5400.594955378323, 5521.641093328921, 5530.557555395599, 5552.250446189793, 5601.159826026088, 5564.749586268627, 5901.144334071536, 5946.583739138262, 6177.444030037318, 6234.158388617524, 6291.276503202144, 6329.683515824237, 6333.461655759266, 6366.904496432558, 6389.4568961638315, 5561.721913904288, 6580.0787889695, 6581.407673309377, 6592.472602191813, 6632.204135503722, 6232.781852635136, 6254.249490061131, 6273.215848018997, 6282.905756413657, 6318.368163183737, 5614.887702246014, 5666.886662266732, 6268.811935641938, 6389.6805159741825, 6668.699908504129, 5677.347105082886, 6104.6874921892, 6306.873862522724, 6369.5222858285815, 6377.457916077093, 6204.503639708812, 6454.451371633464, 6518.511111666644, 6533.216014078341, 6549.279579246279, 6175.752969881154, 6204.0, 6349.0, 6465.579737317059, 6467.126937863328, 5984.3417224105315, 6216.471599913967, 6432.935670643327, 6437.130987316746, 6456.289808121103, 5153.201253805666, 5193.480651934777, 5209.453007434241, 5235.39792923811, 5258.027264955996, 3914.784686842194, 4616.788683213312, 4652.95347046532, 4668.346431499593, 4716.905661886812, 5965.612235204698, 6004.969975150097, 6018.789342373084, 6025.9397406026255, 6307.810765677037, 6431.196100487439, 6500.739970401226, 6544.181977252843, 6550.148480697479, 6554.606723596599, 6179.16581261533, 6186.597871138361, 6195.349488303754, 6201.720922558496, 6217.409346112112, 5632.577556683265, 5839.0, 5854.2096816930025, 5868.853278667982, 5882.470577647947, 6614.0, 6677.69950352245, 6712.496562757715, 6728.529002969745, 6747.966260168668, 6512.45071436059, 6579.243387010363, 6584.901226481702, 6589.308122647245, 6593.142891424084, 6000.69996500182, 6780.898286525608, 6815.454763618799, 6819.727210911508, 6828.897566536605, 5245.868853278546, 5540.390557038803, 6047.848803779941, 6189.690515474298, 6318.589291696025, 6545.967270163619, 6553.082568440517, 6555.3772391622715, 6557.540972131905, 6585.901211481736, 5823.563232757476, 6040.637561746309, 6335.524835637253, 6367.140436041261, 6375.139356186855, 5684.459976302244, 6170.413810687977, 6201.162843016135, 6355.3326900674765, 6383.0, 5413.539849112712, 6099.634021958696, 6232.657203853832, 6259.874802504016, 6260.812175634738, 5831.825045248649, 6276.167271642275, 6287.119803227643, 6339.144215531029, 6341.524385671185, 5803.0, 6197.690115494117, 6223.906641400292, 6236.594621349598, 6262.592931459622], "value": 5923.989669219371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.5683746589511, 797.7686470923433, 823.2549542663887, 938.2915898496634, 939.1594522902008, 37805.0, 38432.231355372875, 40268.597314026854, 40304.0, 40305.79082627526, 38569.8071509642, 38714.0, 38738.41892371617, 38873.41689874654, 39023.414648780294, 7760.107587627424, 7818.0, 9184.265258779315, 9943.552540135712, 10691.465426728671, 10447.477626118702, 10550.683479495627, 10553.261271710984, 10553.472326383688, 10592.41741704206, 10540.20948428867, 10576.36541807494, 10610.840837387444, 10637.0, 10638.521266543024, 8985.326100542485, 10515.78968420635, 10558.947205264001, 10570.260081794277, 10591.205659575562, 10349.948250258776, 10386.948065259625, 10393.220508461896, 10396.948015259875, 10407.959204079532, 33958.0, 34079.14802129942, 34110.82944585286, 34118.805841795416, 34178.0, 3304.9173770655684, 3601.3877640801124, 3651.8721844735574, 3695.9445608315896, 3728.2170744143764, 24725.010999559996, 24824.0, 25606.359841003938, 25684.357891052685, 26413.3396665083, 2970.5692674562283, 3008.7592992560544, 3147.323325485009, 3223.6453990061073, 3229.40256052631, 348900.0, 352831.0, 353109.99999999744, 353407.0, 354203.0, 35192.824035879654, 43633.56366436359, 43756.68729938107, 44013.559864401584, 44015.0, 44135.0, 44158.11683766307, 44262.55737442584, 44417.33373999393, 44427.77786111049, 13822.861771382359, 13862.237576933163, 13929.442822286996, 13934.442622295197, 13952.16287022782, 11743.11926605508, 11897.226680265756, 11932.940335298268, 11959.282443053444, 11981.562212534378, 9866.408015519091, 11692.88307116935, 11816.645500634995, 11892.286462812259, 11908.821367679491, 5186.8329625833885, 5365.0, 5607.775688972477, 5654.9151762723595, 5708.80019199326, 952.7379970508166, 959.1080295325274, 965.2663975378763, 1014.3862962907482, 1042.7862288230895, 29565.55651665227, 29772.553411698842, 29895.8493409407, 29989.0, 30050.398992020037, 1005.1908213887745, 1054.0724162736742, 1054.7995880782628, 1065.504540388723, 1083.7994970930404, 14861.405543778128, 14965.401383944738, 15049.247537623076, 15049.699006020037, 15553.766693499607, 495381.0, 496210.0, 496439.0, 498611.0, 500612.0, 45096.77451612721, 45290.094198115854, 45382.0, 47383.76308118437, 48706.75646621744, 1372.8215332006775, 1410.6825964157983, 1413.1168019987508, 1413.8232720909887, 1415.936282867294, 22097.668534971668, 26184.60723089117, 26995.0, 27082.458350833265, 27106.593401099395, 40673.38989915213, 40871.77384678464, 40998.0, 41114.79442602887, 41138.588614113476, 416653.0, 484664.0, 485028.0, 487405.0, 489827.10172897816, 29502.409951801274, 29735.0, 29792.0, 30102.397952041276, 30172.0, 38667.0, 39092.82721518359, 39436.40845387379, 39554.406683899186, 39579.60420395759, 26327.605085923344, 28189.43621127607, 28413.573796393484, 29066.982655606953, 29345.55981660234, 28003.0198943036, 28379.864805407553, 28428.289292767848, 28989.565156522247, 29154.0, 31993.360132796748, 32199.0, 32566.023019309458, 33128.0, 33325.0, 28898.85550572234, 28908.566371504025, 29330.853345733136, 29334.41331173407, 29781.702182977893, 20898.89550552298, 22492.550148997256, 23050.0, 24241.03035878545, 24612.01551937974], "value": 17978.029070747893, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.971651984361, 407.8164825828378, 417.27394333859087, 434.0624251616508, 447.7335985088873, 17597.912010439963, 19934.900325498387, 20488.897555512238, 21273.04271307791, 21418.89290553549, 19983.300584479544, 20114.69827952582, 20407.979601019964, 20879.164833406685, 21127.260545880898, 6686.364795344447, 6996.0, 7028.859422811541, 7056.1179852518435, 7066.434685225196, 6623.0, 6869.896951545731, 6888.690008949584, 6920.75777347793, 6973.232944376115, 6645.6344901030425, 6939.548929319603, 6967.442604591621, 6985.860282794341, 6986.9301306987045, 6873.0, 7010.263922288188, 7012.123484564429, 7020.929790702079, 7023.157221133446, 6761.560498567577, 6771.052052712625, 6777.966110169467, 6866.588004719682, 6869.210040845317, 22882.77117228823, 26733.866330668418, 27033.459330813275, 27335.726642733516, 27373.863130684418, 2915.9271018224504, 2924.7513961313316, 2947.2189869684553, 2998.9803466821168, 3016.1776802851573, 16342.673146537003, 16480.42318518846, 16492.587685307844, 16523.421680241136, 16772.909760865627, 2021.3632705697805, 2054.301537477265, 2055.835533157344, 2062.3709768520594, 2108.68369744538, 101625.9837401624, 132628.0, 136207.31896340553, 140533.0, 144588.55411445856, 22676.65985010226, 22924.218367733967, 23026.65460018101, 23223.883880580488, 23266.53466930652, 27845.86077069642, 31396.37207255842, 35792.64207357945, 36110.458343124876, 36115.458268126, 10915.23593348458, 11066.889331106586, 11127.610533631447, 11194.272372295774, 11203.271787333797, 8951.373403861668, 8980.057094005166, 9005.684801031932, 9108.8633670495, 9156.313276504156, 8780.692675756318, 10375.481225938673, 10656.520456579472, 10753.408562528979, 10839.729006774895, 4432.911341773147, 4482.305242687388, 4495.775211239425, 4529.411176547028, 4533.569310915524, 947.516766449116, 1100.2958106811648, 1120.6525976947173, 1125.099920063962, 1160.5009845766233, 23153.073877045066, 23221.53556928852, 23320.067197311917, 23322.533549328917, 23340.883295583415, 1130.519529200103, 1137.9146564007733, 1154.630518234157, 1158.5250047480492, 1169.9707507312385, 9262.703221548989, 11958.58144964922, 12148.392580370946, 12354.876451235372, 12372.938135309267, 668250.0, 715714.0, 748174.0, 749432.0, 754591.0, 129414.35292823476, 135552.0, 142075.0, 165255.1737241306, 170103.0, 1237.9814302785464, 1241.3855141704914, 1303.2310936547483, 1305.4386613755987, 1306.9085164038427, 19161.94609296502, 20094.59810803805, 20383.18467261293, 20675.68986465174, 20746.896265518575, 48939.75530122327, 54512.72743636256, 63924.360756393704, 66325.33674663192, 66349.33650663431, 92072.53963730407, 92868.0, 92907.53546232225, 93763.24402837212, 93917.5304123475, 9260.768480788034, 11502.654920352403, 11532.135089868461, 11707.590234341757, 11746.29522228669, 12467.87532124667, 12568.245905245714, 12991.480340786262, 13182.934085329896, 13223.3388330585, 10514.737131571772, 10827.13382929348, 11186.937240962096, 11224.15818813576, 11477.598284060017, 10568.894311057098, 10777.568897244022, 10944.233903626988, 10944.671659850217, 11129.220954533104, 11574.30554166719, 11929.761404772029, 12008.879911200776, 12046.156769026084, 12129.45417456199, 10811.513481893177, 11178.552857885594, 11615.128865334964, 11999.100067494799, 12197.817032744339, 10947.507362168562, 11000.22998390105, 11056.613018544313, 11148.609798657008, 11250.0], "value": 11868.324237056433, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.7197904377208, 1572.1274692545633, 1591.7771511988324, 1591.9174961026504, 1601.5595711179428, 10586.41774702391, 11062.280951738132, 11171.832422513658, 11172.217944743872, 11396.373199474026, 10960.232783705143, 11157.330560166396, 11161.939615736492, 11164.162687798407, 11176.664700059011, 2382.9046838126455, 2415.830891837572, 2434.6834911461483, 2436.0742917691255, 2438.7317395086525, 2113.714648522453, 2319.9652005219937, 2375.714914210297, 2410.674558934548, 2412.1436889904094, 2311.84972976756, 2391.940201494968, 2395.3652282145244, 2401.7838394544488, 2403.531311394279, 2189.1681161158817, 2320.234322673514, 2360.9645855312183, 2374.133441293924, 2374.6675465434855, 2212.5353675727956, 2353.176388264094, 2361.5276944611146, 2369.680093187415, 2376.489054853215, 9331.253499720005, 10977.451127443675, 11118.221724479208, 11175.273607215506, 11177.49701263445, 4900.779464924087, 4997.675151115166, 5705.172749951275, 5783.5084017858535, 5926.170336152942, 3594.676479116892, 3655.4882316475714, 3659.2132691471197, 3685.649863262986, 3706.1475860551955, 1206.1737709668905, 1213.1083653514659, 1243.9564615238514, 1263.5198624522714, 1269.574692478021, 12157.66265710771, 13716.794248086288, 13902.582922512342, 14709.558713238514, 15217.391304347813, 4612.723236605814, 4650.51169627191, 4797.160496913027, 4803.375561177095, 4804.927926081112, 11796.351200684045, 12013.399330033464, 12031.398430078461, 12040.0, 12131.45408456604, 5808.157817116579, 5868.853278668067, 5874.265716785402, 5875.676837773878, 5883.17635531026, 5122.206058061007, 5157.63896527247, 5213.6350455467755, 5219.060569097599, 5262.2632831403635, 3825.292320920583, 3905.4922860028014, 3918.6676529979945, 3949.407588861636, 3995.62041606047, 914.8947870994788, 1038.9740256493649, 1043.9582416703247, 1050.81610718124, 1053.6944286157081, 807.4428644235459, 910.3127139010005, 914.6890057380523, 917.6191880369572, 953.4041224234853, 6276.497880169667, 6755.324467553205, 6775.559588626724, 6780.457563394916, 6848.417884479775, 995.348047029197, 996.6262651505685, 1004.8995100489892, 1011.2264117949775, 1035.984460233097, 4297.656187504992, 4316.719413238192, 4361.890952726208, 4446.866594002185, 4471.530489298641, 13283.800742988862, 13833.584992450242, 13933.512327068504, 13940.860591393955, 13961.162330260217, 13451.92384609229, 13679.384427700777, 13836.962227832953, 13840.169589824643, 13865.722685546232, 8714.389992700577, 10197.286189966631, 10259.846102308471, 10296.639617613348, 10303.639372621923, 5559.555235581063, 5926.644401335933, 6566.242178285036, 6849.24658287578, 6949.7567585134275, 12112.45493952792, 12118.636440906786, 12123.15137940371, 12134.635960921187, 12196.0, 10205.38767673942, 12407.565735199225, 12418.565350212699, 12436.37818109109, 12654.304013279358, 8407.831843363221, 8534.591792354193, 8551.828963420572, 8877.689280875138, 8885.0, 10556.419396933243, 10651.0, 10670.0, 10677.359358438207, 10678.73303167427, 8834.558272086499, 8899.911000889908, 8950.731478055668, 9012.369134160808, 9013.684521041732, 7551.962240188764, 8772.692955746517, 8830.558472076498, 8855.690050848189, 8858.114188581349, 7850.921490785019, 7858.0, 7883.2905038546805, 7917.881231781413, 7968.721094761888, 5915.231019967377, 5957.076653118774, 6019.0068638675875, 6036.63780173191, 6076.270847498328, 4801.4023898069845, 5953.255843019622, 6112.510999120149, 6193.0, 6242.375762423721], "value": 5117.0823007435065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.77198137154966, 371.80480247869866, 381.9885403437897, 383.9117003089289, 387.7750904475404, 4319.855238361836, 4671.439427268724, 5130.127503461233, 5148.841510660104, 5338.973305133479, 4466.754328511931, 4963.578095861856, 5033.622478314122, 5089.2620570017325, 5142.637201141701, 3839.8129552471523, 4212.789360531969, 4240.469941257343, 4245.936310955338, 4274.978625106885, 4269.6370808481315, 4282.807273672676, 4295.935560966588, 4321.697481176303, 4326.805293761772, 3956.3669812829953, 4182.811773470201, 4241.5122260939925, 4270.807813648377, 4277.165952639237, 4093.6315731584004, 4187.623113919732, 4208.642265407444, 4254.042840360925, 4298.505671847746, 3186.3308705171944, 3566.3402270580027, 3898.0, 3900.609939006105, 3948.8153553933994, 4208.894777630584, 4999.850004499871, 5011.649184557083, 5043.62172837035, 5043.722595257296, 4737.620990320766, 4836.524859917724, 4877.951220487821, 4959.8264060758065, 5033.572146367563, 4588.678792484528, 5424.810131645373, 5539.972300138514, 5555.194496797942, 5598.776048958037, 3917.491765670209, 3931.2334094851517, 3960.742551734128, 4133.904515303447, 4305.246581848195, 5069.315642388267, 5138.460461651547, 5588.189712491666, 5769.798057067983, 5805.071188609802, 4100.302948498763, 4323.557190187534, 4436.689431739812, 4450.261780104727, 4528.841490547815, 3677.8528858845348, 4061.005053761847, 4249.787510624456, 4343.543927887589, 4581.656375771764, 4269.850555230552, 4626.537346265414, 4889.339939108246, 4910.754462276872, 4935.531124543163, 4870.245112007645, 5228.738563071831, 5266.605004624668, 5500.862478437991, 5564.8330550083565, 1508.5549762819994, 1591.368846931904, 1701.3534856754354, 1714.545645403944, 1734.8661146789268, 1206.9517219311128, 1323.6823162440944, 1328.2827273272326, 1412.1174266083697, 1412.8233970753656, 4697.342372067913, 4751.691140075884, 4971.6271279654175, 5001.574866136347, 5138.948610513922, 5737.540996720252, 5740.540756739534, 5741.425857414309, 5787.479126878601, 5791.536677065825, 4492.9550704493195, 4580.3968610985885, 5126.410462796826, 5132.769025393866, 5191.819435836957, 2627.973720262773, 2854.4576530459144, 2905.186547766629, 2967.3827763868744, 2968.3172870239964, 5341.7062061586985, 5360.624756267024, 5535.564351685246, 5966.164736936832, 5986.73059712314, 5087.592992560586, 5508.889822203613, 5649.655393011936, 5896.410358964069, 5908.911366329508, 5561.416051314553, 5901.514848715007, 5908.291005079332, 5912.852178695481, 5915.994280972245, 5009.096543313518, 5291.814786482455, 5373.811916583058, 5628.2833735711, 5720.942790572041, 4964.939550086674, 5467.976848566056, 5494.395616482104, 5629.380768115422, 5703.258576385043, 5109.335786347751, 5308.654937429054, 5335.532728499694, 5472.972635136799, 5644.463775941279, 4408.823647054211, 4615.353850460871, 4638.373819534455, 4968.304437378698, 4999.300097986357, 4950.871125415962, 5165.92251116226, 5477.67133971963, 5487.052096505683, 5647.0964645656495, 4713.929291060568, 5200.6879587224885, 5203.375594928632, 5498.862528436661, 5501.697406642673, 4487.371767952554, 5332.893342133213, 5577.0, 5611.859703507445, 5622.746976385991, 4442.933355999728, 5327.9467205329, 5328.600354973315, 5344.893102137858, 5381.704006279536, 4796.664233503621, 5132.738942583881, 5325.414204437587, 5484.095124304605, 5623.297087864017, 4186.413902053776, 4703.3650457189215, 5319.255304257485, 5421.268128802719, 5447.155690867922], "value": 4225.668986235913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.8651599454098, 770.7014276846679, 779.9844003119938, 828.1511450762963, 843.5782108945527, 15114.669385348112, 16245.756313655305, 16714.997100173998, 16754.91622541887, 16764.83235167648, 15391.769123463157, 15435.69128617427, 15569.221538923066, 15582.376704931788, 15600.375984960616, 923.3905622289291, 944.0, 948.6300342866288, 949.3259785552266, 949.9620015199383, 915.057490784493, 940.6190492850372, 941.9858702119474, 943.2077055273569, 943.5895385507292, 991.5587563534231, 1008.6318493749765, 1008.8234558952154, 1016.6086056868085, 1017.9083882450578, 848.944818586796, 1004.7739258666815, 1008.9545970431349, 1009.0666133826181, 1010.3988127064422, 742.936850367714, 749.6401727170934, 750.114864459937, 750.1186106325047, 750.6884642873201, 16400.671986560323, 16886.746698799405, 16923.91538042302, 16978.575535611584, 17211.827881721147, 12735.872641273654, 13055.347232638425, 13085.86914130856, 13168.144070635473, 13236.568135801246, 11459.31244125355, 11735.882641173564, 11747.471363788565, 12044.39778011096, 12108.576199832964, 3672.06362377592, 3682.502862113607, 3711.0722319420147, 3729.160938788778, 3867.903302417434, 59225.0, 67632.64734705279, 102930.0, 110282.44858775735, 111441.0, 11109.333439993627, 11123.499442524944, 11141.665750027512, 11189.832152517718, 11325.71685707864, 22188.667169992463, 22300.44248893758, 22363.329100127023, 22444.55110897773, 22546.0, 7561.886571701319, 7958.801029974181, 8036.196380362034, 8116.918830811616, 8144.633491492897, 4341.630961368256, 4410.757408342572, 4434.600885920283, 4458.665600079942, 4609.838655647103, 3458.8962331130106, 3503.649635036476, 3516.982415087908, 3524.3127590120193, 3541.6812486876306, 633.4678869749432, 644.1207751419279, 647.3591144766741, 662.3906006474056, 666.0907860770014, 657.9539432239793, 675.5507587454288, 688.5317983770983, 692.5775277080977, 727.2727272727273, 9744.512774361254, 10634.946825265979, 10636.787264254672, 10774.515146818412, 10878.782424351626, 697.1390332938817, 701.9227884932653, 707.2927072927073, 723.450177864816, 729.702665992492, 4987.0, 5117.232415137759, 5117.795288188505, 5120.771014956384, 5146.150885103991, 70300.0, 76423.0, 94844.0515594849, 96096.0, 108801.19195828108, 81505.59247203726, 82446.0, 83771.58114209512, 85373.57313213394, 96411.51794241124, 54126.0, 71547.2845271551, 74037.6298118506, 74618.99999999891, 76295.0, 10683.94658026705, 11120.555177792798, 11193.27243729155, 11341.943290283496, 11342.20604557706, 47728.0, 48878.75560622174, 49263.0, 50209.0, 50975.0, 66900.0, 68088.65955670387, 72363.19092022741, 72496.0, 73339.0, 13043.673908152372, 13120.485899523506, 13157.210567365988, 13354.998375122099, 13863.4454621814, 15052.397904083715, 15103.0, 15330.540083797503, 15428.997115187476, 15932.283047263132, 13800.792988104986, 13823.792643110562, 14388.35252413623, 14400.567982960529, 14645.487407941097, 13512.79730804019, 13960.441582336593, 14076.085054471427, 14087.647808804863, 14177.858221417653, 13239.40422681001, 13429.66425839362, 13775.448982040607, 13903.652408689864, 14090.577282681536, 9478.620855165991, 9994.400335979864, 10086.0, 10102.848457273294, 10346.844797327896, 11112.499937502671, 11185.660122674939, 11261.66215013551, 11273.154513411691, 11332.036776874058], "value": 7695.475045768595, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 175.0, 119.0, 120.0, 119.0, 135.0, 175.0, 158.0, 120.0, 120.0], "value": 136.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 103.0, 111.0, 174.0, 138.0, 120.0, 118.0, 120.0, 158.0, 138.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 198.0, 200.0, 198.0, 197.0, 203.0, 200.0, 198.0, 203.0, 198.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 61.0, 88.0, 61.0, 60.0, 61.0, 61.0, 74.0, 59.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 103.0, 86.0, 95.0, 100.0, 114.0, 72.0, 71.0, 84.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 96.0, 91.0, 100.0, 93.0, 94.0, 92.0, 93.0, 93.0, 93.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 105.0, 99.0, 100.0, 118.0, 110.0, 100.0, 100.0, 98.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 90.0, 115.0, 111.0, 92.0, 93.0, 84.0, 91.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 83.0, 103.0, 104.0, 106.0, 104.0, 106.0], "value": 87.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 66.0, 59.0, 70.0, 69.0, 63.0, 58.0, 59.0, 59.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 88.0, 85.0, 86.0, 86.0, 89.0, 85.0, 87.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 150.0, 168.0, 148.0, 163.0, 145.0, 139.0, 157.0, 202.0, 155.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 171.0, 171.0, 171.0, 169.0, 170.0, 165.0, 164.0, 166.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 68.0, 67.0, 66.0, 64.0, 66.0, 66.0], "value": 66.4, "unit": "score"}]}]} 11:29:01 INFO - Return code: 0 11:29:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:29:01 ERROR - # TBPL SUCCESS # 11:29:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:01 INFO - 2017-04-10 11:29:01.744 screenresolution[2955:49474] starting screenresolution argv=screenresolution get 11:29:01 INFO - 2017-04-10 11:29:01.747 screenresolution[2955:49474] Display 0: 1600x1200x32@60 11:29:01 INFO - 2017-04-10 11:29:01.753 screenresolution[2956:49477] starting screenresolution argv=screenresolution list 11:29:01 INFO - Available Modes on Display 0 11:29:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:01 INFO - Intel Iris: 11:29:01 INFO - Chipset Model: Intel Iris 11:29:01 INFO - Type: GPU 11:29:01 INFO - Bus: Built-In 11:29:01 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:01 INFO - Vendor: Intel (0x8086) 11:29:01 INFO - Device ID: 0x0a2e 11:29:01 INFO - Revision ID: 0x0009 11:29:01 INFO - Displays: 11:29:01 INFO - Display: 11:29:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:01 INFO - Display Serial Number: 1600x1200 60 11:29:01 INFO - Main Display: Yes 11:29:01 INFO - Mirror: Off 11:29:01 INFO - Online: Yes 11:29:01 INFO - Rotation: Supported 11:29:01 INFO - Return code: 0 11:29:01 INFO - Running post-action listener: _resource_record_post_action 11:29:01 INFO - [mozharness: 2017-04-10 18:29:01.993008Z] Finished run-tests step (success) 11:29:01 INFO - Running post-run listener: _resource_record_post_run 11:29:02 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 335872; Write bytes: 513585152; Read time: 3; Write time: 1628 11:29:02 INFO - TinderboxPrint: CPU usage
54.7% 11:29:02 INFO - TinderboxPrint: I/O read bytes / time
335,872 / 3 11:29:02 INFO - TinderboxPrint: I/O write bytes / time
513,585,152 / 1,628 11:29:02 INFO - TinderboxPrint: CPU idle
2,175.1 (45.3%) 11:29:02 INFO - TinderboxPrint: CPU system
972.3 (20.2%) 11:29:02 INFO - TinderboxPrint: CPU user
1,658.4 (34.5%) 11:29:02 INFO - TinderboxPrint: Swap in / out
207,945,728 / 0 11:29:02 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205685248; Write bytes: 201295872; Read time: 16420; Write time: 439 11:29:02 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 331776; Write bytes: 301221888; Read time: 2; Write time: 1153 11:29:02 INFO - Running post-run listener: _upload_blobber_files 11:29:02 INFO - Blob upload gear active. 11:29:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:29:02 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 11:29:02 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'] 11:29:02 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 11:29:02 INFO - (blobuploader) - INFO - Open directory for files ... 11:29:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:29:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:29:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:03 INFO - (blobuploader) - INFO - Done attempting. 11:29:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:29:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:29:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:04 INFO - (blobuploader) - INFO - Done attempting. 11:29:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:29:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:06 INFO - (blobuploader) - INFO - Done attempting. 11:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:08 INFO - (blobuploader) - INFO - Done attempting. 11:29:08 INFO - (blobuploader) - INFO - Iteration through files over. 11:29:08 INFO - Return code: 0 11:29:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:29:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:29:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7744bef3dca3a56dd2f5fac736329645a3d74988dbf515e8191eaabcb051a708de5df15d034e66ca9dd8bd04365e59917aa2925d3b5464b82417e4e3d73357d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0c513678705f0cd31dd9990611c3b06924256f5dd5e83645b45191c73f26842c409228ada61e674d41d81d28db91467634c7c6e99dbb9e9b59c7fa0ef045ae9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85bf91d8076fb1503e1c8c64a33f4c2ceef8ad08417c48aa17f21029b39b2da40c334ef8dd2f2c907dc5db28ba6db4c15eb7c8c79dbac2df409a226774e58220", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ea35e1ee9b52525ef150ba413ae95b182ce0b0c6a7a3bbf60ac54d7de34751b8cf0bb4647628349eca59a48339b10933e5f4e280342c4604d4d8fdf10071247"} 11:29:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:29:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:29:08 INFO - Contents: 11:29:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7744bef3dca3a56dd2f5fac736329645a3d74988dbf515e8191eaabcb051a708de5df15d034e66ca9dd8bd04365e59917aa2925d3b5464b82417e4e3d73357d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0c513678705f0cd31dd9990611c3b06924256f5dd5e83645b45191c73f26842c409228ada61e674d41d81d28db91467634c7c6e99dbb9e9b59c7fa0ef045ae9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85bf91d8076fb1503e1c8c64a33f4c2ceef8ad08417c48aa17f21029b39b2da40c334ef8dd2f2c907dc5db28ba6db4c15eb7c8c79dbac2df409a226774e58220", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ea35e1ee9b52525ef150ba413ae95b182ce0b0c6a7a3bbf60ac54d7de34751b8cf0bb4647628349eca59a48339b10933e5f4e280342c4604d4d8fdf10071247"} 11:29:08 INFO - Running post-run listener: copy_logs_to_upload_dir 11:29:08 INFO - Copying logs to upload dir... 11:29:08 INFO - mkdir: /builds/slave/test/build/upload/logs 11:29:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.844768 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-10 11:29:08.735040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:08.738388) ========= 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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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/7744bef3dca3a56dd2f5fac736329645a3d74988dbf515e8191eaabcb051a708de5df15d034e66ca9dd8bd04365e59917aa2925d3b5464b82417e4e3d73357d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0c513678705f0cd31dd9990611c3b06924256f5dd5e83645b45191c73f26842c409228ada61e674d41d81d28db91467634c7c6e99dbb9e9b59c7fa0ef045ae9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85bf91d8076fb1503e1c8c64a33f4c2ceef8ad08417c48aa17f21029b39b2da40c334ef8dd2f2c907dc5db28ba6db4c15eb7c8c79dbac2df409a226774e58220", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ea35e1ee9b52525ef150ba413ae95b182ce0b0c6a7a3bbf60ac54d7de34751b8cf0bb4647628349eca59a48339b10933e5f4e280342c4604d4d8fdf10071247"} build_url:https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011589 build_url: 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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/7744bef3dca3a56dd2f5fac736329645a3d74988dbf515e8191eaabcb051a708de5df15d034e66ca9dd8bd04365e59917aa2925d3b5464b82417e4e3d73357d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f0c513678705f0cd31dd9990611c3b06924256f5dd5e83645b45191c73f26842c409228ada61e674d41d81d28db91467634c7c6e99dbb9e9b59c7fa0ef045ae9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85bf91d8076fb1503e1c8c64a33f4c2ceef8ad08417c48aa17f21029b39b2da40c334ef8dd2f2c907dc5db28ba6db4c15eb7c8c79dbac2df409a226774e58220", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ea35e1ee9b52525ef150ba413ae95b182ce0b0c6a7a3bbf60ac54d7de34751b8cf0bb4647628349eca59a48339b10933e5f4e280342c4604d4d8fdf10071247"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:08.773052) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:08.773409) ========= 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.G4SLPkDgAN/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.7eEmzmMyHN/Listeners TMPDIR=/var/folders/_y/5qjgk6c15rn_gtyl8d944nb400000w/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.005361 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:08.822722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 11:29:08.823114) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 11:29:08.823483) ========= ========= Total master_lag: 0.19 =========