builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0166 starttime: 1492852370.78 results: success (0) buildid: 20170422004007 builduid: 36ee54a1d6054a788963931fbb74cdb4 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.778701) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.779151) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.779439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.806654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.806950) ========= 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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-22 02:12:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 69.5M=0s 2017-04-22 02:12:50 (69.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137138 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.968459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:50.968750) ========= 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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.031267 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.023361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.023704) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-22 02:12:51,124 truncating revision to first 12 chars 2017-04-22 02:12:51,124 Setting DEBUG logging. 2017-04-22 02:12:51,124 attempt 1/10 2017-04-22 02:12:51,124 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-22 02:12:51,784 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.931396 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.979157) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.979426) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.979828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:51.980088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-22 02:12:52.002400) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-22 02:12:52.002713) ========= /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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:12:52 INFO - MultiFileLogger online at 20170422 02:12:52 in /builds/slave/test 02:12:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:12:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:12:52 INFO - {'append_to_log': False, 02:12:52 INFO - 'base_work_dir': '/builds/slave/test', 02:12:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:12:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:12:52 INFO - 'branch': 'Mozilla-Aurora', 02:12:52 INFO - 'buildbot_json_path': 'buildprops.json', 02:12:52 INFO - 'config_files': ('talos/mac_config.py',), 02:12:52 INFO - 'default_actions': ('clobber', 02:12:52 INFO - 'read-buildbot-config', 02:12:52 INFO - 'download-and-extract', 02:12:52 INFO - 'populate-webroot', 02:12:52 INFO - 'create-virtualenv', 02:12:52 INFO - 'install', 02:12:52 INFO - 'run-tests'), 02:12:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:12:52 INFO - 'download_minidump_stackwalk': True, 02:12:52 INFO - 'download_symbols': 'ondemand', 02:12:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:12:52 INFO - 'tooltool.py': '/tools/tooltool.py', 02:12:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:12:52 INFO - '/tools/misc-python/virtualenv.py')}, 02:12:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:12:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:12:52 INFO - 'gecko_profile': False, 02:12:52 INFO - 'gecko_profile_interval': 0, 02:12:52 INFO - 'installer_path': 'installer.exe', 02:12:52 INFO - 'log_level': 'info', 02:12:52 INFO - 'log_name': 'talos', 02:12:52 INFO - 'log_to_console': True, 02:12:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:12:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:12:52 INFO - 'opt_config_files': (), 02:12:52 INFO - 'pip_index': False, 02:12:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:12:52 INFO - 'cmd': ('bash', 02:12:52 INFO - '-c', 02:12:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:12:52 INFO - 'enabled': True, 02:12:52 INFO - 'halt_on_failure': False, 02:12:52 INFO - 'name': 'check_screen_resolution'},), 02:12:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:12:52 INFO - 'cmd': ('bash', 02:12:52 INFO - '-c', 02:12:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:12:52 INFO - 'enabled': True, 02:12:52 INFO - 'halt_on_failure': False, 02:12:52 INFO - 'name': 'check_screen_resolution'},), 02:12:52 INFO - 'run_cmd_checks_enabled': True, 02:12:52 INFO - 'suite': 'dromaeojs-e10s', 02:12:52 INFO - 'system_bits': '32', 02:12:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:12:52 INFO - 'title': 't-yosemite-r7-0166', 02:12:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:12:52 INFO - 'use_talos_json': True, 02:12:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:12:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:12:52 INFO - 'work_dir': 'build'} 02:12:52 INFO - [mozharness: 2017-04-22 09:12:52.216096Z] Running clobber step. 02:12:52 INFO - Running pre-action listener: _resource_record_pre_action 02:12:52 INFO - Running main action method: clobber 02:12:52 INFO - rmtree: /builds/slave/test/build 02:12:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:12:54 INFO - Running post-action listener: _resource_record_post_action 02:12:54 INFO - [mozharness: 2017-04-22 09:12:54.681652Z] Finished clobber step (success) 02:12:54 INFO - [mozharness: 2017-04-22 09:12:54.681798Z] Running read-buildbot-config step. 02:12:54 INFO - Running pre-action listener: _resource_record_pre_action 02:12:54 INFO - Running main action method: read_buildbot_config 02:12:54 INFO - Using buildbot properties: 02:12:54 INFO - { 02:12:54 INFO - "project": "", 02:12:54 INFO - "product": "firefox", 02:12:54 INFO - "script_repo_revision": "production", 02:12:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:12:54 INFO - "repository": "", 02:12:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:12:54 INFO - "buildid": "20170422004007", 02:12:54 INFO - "stage_platform": "macosx64", 02:12:54 INFO - "basedir": "/builds/slave/test", 02:12:54 INFO - "nightly_build": "True", 02:12:54 INFO - "buildnumber": 108, 02:12:54 INFO - "slavename": "t-yosemite-r7-0166", 02:12:54 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:12:54 INFO - "platform": "yosemite_r7", 02:12:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:12:54 INFO - "branch": "mozilla-aurora", 02:12:54 INFO - "repo_path": "releases/mozilla-aurora", 02:12:54 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:12:54 INFO - "pgo_build": "False", 02:12:54 INFO - "builduid": "36ee54a1d6054a788963931fbb74cdb4", 02:12:54 INFO - "slavebuilddir": "test" 02:12:54 INFO - } 02:12:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:12:54 INFO - Running post-action listener: _resource_record_post_action 02:12:54 INFO - [mozharness: 2017-04-22 09:12:54.685166Z] Finished read-buildbot-config step (success) 02:12:54 INFO - [mozharness: 2017-04-22 09:12:54.685274Z] Running download-and-extract step. 02:12:54 INFO - Running pre-action listener: _resource_record_pre_action 02:12:54 INFO - Running main action method: download_and_extract 02:12:54 INFO - mkdir: /builds/slave/test/build/tests 02:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:54 INFO - trying https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:12:54 INFO - Downloading https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:12:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:12:55 INFO - Downloaded 1199 bytes. 02:12:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:12:55 INFO - Using the following test package requirements: 02:12:55 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:12:55 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:12:55 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:12:55 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'jsshell-mac.zip'], 02:12:55 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:12:55 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:12:55 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:12:55 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:12:55 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:12:55 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:12:55 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:12:55 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:12:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:12:55 INFO - Fetch https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:12:57 INFO - Expected file size: 51791209 02:12:57 INFO - Obtained file size: 51791209 02:13:13 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:13:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:13:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:13:13 INFO - Fetch https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:13:15 INFO - Expected file size: 51791209 02:13:15 INFO - Obtained file size: 51791209 02:13:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:13:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:13:30 INFO - Fetch https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:13:31 INFO - Expected file size: 14404424 02:13:31 INFO - Obtained file size: 14404424 02:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:32 INFO - trying https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:13:32 INFO - Downloading https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:13:34 INFO - Downloaded 74781616 bytes. 02:13:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:13:34 INFO - mkdir: /builds/slave/test/properties 02:13:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:13:34 INFO - Writing to file /builds/slave/test/properties/build_url 02:13:34 INFO - Contents: 02:13:34 INFO - build_url:https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:13:34 INFO - Running post-action listener: _resource_record_post_action 02:13:34 INFO - Running post-action listener: set_extra_try_arguments 02:13:34 INFO - [mozharness: 2017-04-22 09:13:34.964743Z] Finished download-and-extract step (success) 02:13:34 INFO - [mozharness: 2017-04-22 09:13:34.964897Z] Running populate-webroot step. 02:13:34 INFO - Running pre-action listener: _resource_record_pre_action 02:13:34 INFO - Running main action method: populate_webroot 02:13:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:13:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:13:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:13:34 INFO - '--tppagecycles', 02:13:34 INFO - '7'], 02:13:34 INFO - 'tests': ['tsvgm']}}, 02:13:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:13:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:13:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:13:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:13:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:13:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:13:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['damp', 'tps']}, 02:13:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'tests': ['damp', 'tps']}, 02:13:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['dromaeo_dom']}, 02:13:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:13:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['basic_compositor_video']}, 02:13:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:13:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'other-e10s': {'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['a11yr', 02:13:34 INFO - 'ts_paint', 02:13:34 INFO - 'tpaint', 02:13:34 INFO - 'sessionrestore', 02:13:34 INFO - 'sessionrestore_no_auto_restore', 02:13:34 INFO - 'tabpaint']}, 02:13:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['tsvgx', 02:13:34 INFO - 'tsvgr_opacity', 02:13:34 INFO - 'tart', 02:13:34 INFO - 'tscrollx', 02:13:34 INFO - 'cart', 02:13:34 INFO - 'tsvg_static']}, 02:13:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:13:34 INFO - 'tsvgr_opacity', 02:13:34 INFO - 'tart', 02:13:34 INFO - 'tscrollx', 02:13:34 INFO - 'cart', 02:13:34 INFO - 'tsvg_static']}, 02:13:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'talos_options': ['--disable-e10s'], 02:13:34 INFO - 'tests': ['tp5o']}, 02:13:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:13:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'talos_options': ['--disable-e10s', 02:13:34 INFO - '--xperf_path', 02:13:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:13:34 INFO - 'tests': ['tp5n']}, 02:13:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:13:34 INFO - 'talos_options': ['--xperf_path', 02:13:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:13:34 INFO - 'tests': ['tp5n']}}, 02:13:34 INFO - 'talos.zip': {'path': '', 02:13:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:13:34 INFO - Running post-action listener: _resource_record_post_action 02:13:34 INFO - [mozharness: 2017-04-22 09:13:34.983342Z] Finished populate-webroot step (success) 02:13:34 INFO - [mozharness: 2017-04-22 09:13:34.983445Z] Running create-virtualenv step. 02:13:34 INFO - Running pre-action listener: _resource_record_pre_action 02:13:34 INFO - Running main action method: create_virtualenv 02:13:34 INFO - Creating virtualenv /builds/slave/test/build/venv 02:13:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:13:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:13:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:13:35 INFO - Using real prefix '/tools/python27' 02:13:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:13:35 INFO - Installing distribute.............................................................................................................................................................................................done. 02:13:38 INFO - Installing pip.................done. 02:13:38 INFO - Return code: 0 02:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:13:38 INFO - Reading from file tmpfile_stdout 02:13:38 INFO - Output received: 02:13:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:13:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:13: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')]} 02:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13: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')]} 02:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:39 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:39 INFO - 'HOME': '/Users/cltbld', 02:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:39 INFO - 'LOGNAME': 'cltbld', 02:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:39 INFO - 'MOZ_NO_REMOTE': '1', 02:13:39 INFO - 'NO_EM_RESTART': '1', 02:13:39 INFO - 'PAGER': '/bin/cat', 02:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:39 INFO - 'PWD': '/builds/slave/test', 02:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:39 INFO - 'SHELL': '/bin/bash', 02:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:39 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:39 INFO - 'USER': 'cltbld', 02:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:39 INFO - 'XPC_FLAGS': '0x0', 02:13:39 INFO - 'XPC_SERVICE_NAME': '0', 02:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:13:39 INFO - Cleaning up... 02:13:39 INFO - Return code: 0 02:13:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:39 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:13:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:39 INFO - 'HOME': '/Users/cltbld', 02:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:39 INFO - 'LOGNAME': 'cltbld', 02:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:39 INFO - 'MOZ_NO_REMOTE': '1', 02:13:39 INFO - 'NO_EM_RESTART': '1', 02:13:39 INFO - 'PAGER': '/bin/cat', 02:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:39 INFO - 'PWD': '/builds/slave/test', 02:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:39 INFO - 'SHELL': '/bin/bash', 02:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:39 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:39 INFO - 'USER': 'cltbld', 02:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:39 INFO - 'XPC_FLAGS': '0x0', 02:13:39 INFO - 'XPC_SERVICE_NAME': '0', 02:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:39 INFO - Downloading/unpacking psutil>=3.1.1 02:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:13:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:13:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:13:43 INFO - Installing collected packages: psutil 02:13:43 INFO - Running setup.py install for psutil 02:13:43 INFO - building 'psutil._psutil_osx' extension 02:13:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:13:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:13:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:13:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:13:44 INFO - building 'psutil._psutil_posix' extension 02:13:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:13:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:13:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:13:44 INFO - ^ 02:13:44 INFO - 1 warning generated. 02:13:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:13:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:13:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:13:44 INFO - Successfully installed psutil 02:13:44 INFO - Cleaning up... 02:13:44 INFO - Return code: 0 02:13:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:44 INFO - 'HOME': '/Users/cltbld', 02:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:44 INFO - 'LOGNAME': 'cltbld', 02:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:44 INFO - 'MOZ_NO_REMOTE': '1', 02:13:44 INFO - 'NO_EM_RESTART': '1', 02:13:44 INFO - 'PAGER': '/bin/cat', 02:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:44 INFO - 'PWD': '/builds/slave/test', 02:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:44 INFO - 'SHELL': '/bin/bash', 02:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:44 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:44 INFO - 'USER': 'cltbld', 02:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:44 INFO - 'XPC_FLAGS': '0x0', 02:13:44 INFO - 'XPC_SERVICE_NAME': '0', 02:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:13:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:13:47 INFO - Installing collected packages: mozsystemmonitor 02:13:47 INFO - Running setup.py install for mozsystemmonitor 02:13:47 INFO - Successfully installed mozsystemmonitor 02:13:47 INFO - Cleaning up... 02:13:47 INFO - Return code: 0 02:13:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:48 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:48 INFO - 'HOME': '/Users/cltbld', 02:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:48 INFO - 'LOGNAME': 'cltbld', 02:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:48 INFO - 'MOZ_NO_REMOTE': '1', 02:13:48 INFO - 'NO_EM_RESTART': '1', 02:13:48 INFO - 'PAGER': '/bin/cat', 02:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:48 INFO - 'PWD': '/builds/slave/test', 02:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:48 INFO - 'SHELL': '/bin/bash', 02:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:48 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:48 INFO - 'USER': 'cltbld', 02:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:48 INFO - 'XPC_FLAGS': '0x0', 02:13:48 INFO - 'XPC_SERVICE_NAME': '0', 02:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:48 INFO - Downloading/unpacking jsonschema==2.5.1 02:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:13:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:51 INFO - Downloading functools32-3.2.3-2.tar.gz 02:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:13:51 INFO - warning: no files found matching '*.txt' 02:13:51 INFO - no previously-included directories found matching 'build' 02:13:51 INFO - no previously-included directories found matching 'dist' 02:13:51 INFO - no previously-included directories found matching '.git*' 02:13:51 INFO - Installing collected packages: jsonschema, functools32 02:13:51 INFO - Running setup.py install for functools32 02:13:51 INFO - warning: no files found matching '*.txt' 02:13:51 INFO - no previously-included directories found matching 'build' 02:13:51 INFO - no previously-included directories found matching 'dist' 02:13:51 INFO - no previously-included directories found matching '.git*' 02:13:51 INFO - Successfully installed jsonschema functools32 02:13:51 INFO - Cleaning up... 02:13:51 INFO - Return code: 0 02:13:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:51 INFO - 'HOME': '/Users/cltbld', 02:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:51 INFO - 'LOGNAME': 'cltbld', 02:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:51 INFO - 'MOZ_NO_REMOTE': '1', 02:13:51 INFO - 'NO_EM_RESTART': '1', 02:13:51 INFO - 'PAGER': '/bin/cat', 02:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:51 INFO - 'PWD': '/builds/slave/test', 02:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:51 INFO - 'SHELL': '/bin/bash', 02:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:51 INFO - 'USER': 'cltbld', 02:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:51 INFO - 'XPC_FLAGS': '0x0', 02:13:51 INFO - 'XPC_SERVICE_NAME': '0', 02:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:13:51 INFO - Cleaning up... 02:13:51 INFO - Return code: 0 02:13:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:51 INFO - 'HOME': '/Users/cltbld', 02:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:51 INFO - 'LOGNAME': 'cltbld', 02:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:51 INFO - 'MOZ_NO_REMOTE': '1', 02:13:51 INFO - 'NO_EM_RESTART': '1', 02:13:51 INFO - 'PAGER': '/bin/cat', 02:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:51 INFO - 'PWD': '/builds/slave/test', 02:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:51 INFO - 'SHELL': '/bin/bash', 02:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:51 INFO - 'USER': 'cltbld', 02:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:51 INFO - 'XPC_FLAGS': '0x0', 02:13:51 INFO - 'XPC_SERVICE_NAME': '0', 02:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:52 INFO - Downloading/unpacking blobuploader==1.2.4 02:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:54 INFO - Downloading blobuploader-1.2.4.tar.gz 02:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:13:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:13:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13:56 INFO - Downloading docopt-0.6.1.tar.gz 02:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:13:56 INFO - Installing collected packages: blobuploader, requests, docopt 02:13:56 INFO - Running setup.py install for blobuploader 02:13:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:13:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:13:56 INFO - Running setup.py install for requests 02:13:56 INFO - Running setup.py install for docopt 02:13:57 INFO - Successfully installed blobuploader requests docopt 02:13:57 INFO - Cleaning up... 02:13:57 INFO - Return code: 0 02:13:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:57 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:13:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:13:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:57 INFO - 'HOME': '/Users/cltbld', 02:13:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:57 INFO - 'LOGNAME': 'cltbld', 02:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:57 INFO - 'MOZ_NO_REMOTE': '1', 02:13:57 INFO - 'NO_EM_RESTART': '1', 02:13:57 INFO - 'PAGER': '/bin/cat', 02:13:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:57 INFO - 'PWD': '/builds/slave/test', 02:13:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:57 INFO - 'SHELL': '/bin/bash', 02:13:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:13:57 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:13:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:57 INFO - 'USER': 'cltbld', 02:13:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:57 INFO - 'XPC_FLAGS': '0x0', 02:13:57 INFO - 'XPC_SERVICE_NAME': '0', 02:13:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-SyTAao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2zAltC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-p9wfc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2fDq7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-uJroYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:13:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-lBmACB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-jqLnSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-eXWvwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-0rQHEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-VW8agm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-YK09D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-sVryqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-K7YPxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-bQd7Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-L91yJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:13:59 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-pjvhUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:13:59 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-cmTk6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:13:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:13:59 INFO - Running setup.py install for manifestparser 02:13:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:13:59 INFO - Running setup.py install for mozcrash 02:13:59 INFO - Running setup.py install for mozdebug 02:13:59 INFO - Running setup.py install for mozdevice 02:13:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:13:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:13:59 INFO - Running setup.py install for mozfile 02:13:59 INFO - Running setup.py install for mozhttpd 02:14:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Running setup.py install for mozinfo 02:14:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Running setup.py install for mozInstall 02:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Running setup.py install for mozleak 02:14:00 INFO - Running setup.py install for mozlog 02:14:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Running setup.py install for moznetwork 02:14:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:14:00 INFO - Running setup.py install for mozprocess 02:14:01 INFO - Running setup.py install for mozprofile 02:14:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:14:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:14:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:14:01 INFO - Running setup.py install for mozrunner 02:14:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:14:01 INFO - Running setup.py install for mozscreenshot 02:14:01 INFO - Running setup.py install for moztest 02:14:01 INFO - Running setup.py install for mozversion 02:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:14:01 INFO - Cleaning up... 02:14:01 INFO - Return code: 0 02:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:01 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:01 INFO - 'HOME': '/Users/cltbld', 02:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:01 INFO - 'LOGNAME': 'cltbld', 02:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:01 INFO - 'MOZ_NO_REMOTE': '1', 02:14:01 INFO - 'NO_EM_RESTART': '1', 02:14:01 INFO - 'PAGER': '/bin/cat', 02:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:01 INFO - 'PWD': '/builds/slave/test', 02:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:01 INFO - 'SHELL': '/bin/bash', 02:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:14:01 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:01 INFO - 'USER': 'cltbld', 02:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:01 INFO - 'XPC_FLAGS': '0x0', 02:14:01 INFO - 'XPC_SERVICE_NAME': '0', 02:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-GGn6wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-nRIlCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Z8ULPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Bxnfif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-giVBi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-jRkcBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Ey8NZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2C801z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-TAonfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Kl3VGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-mDuw6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-S4OeDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-z9Q8_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Xx28m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iW3p1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-lqPaJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-BcdJy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:14:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:06 INFO - Downloading blessings-1.6.tar.gz 02:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:14:06 INFO - Installing collected packages: blessings 02:14:06 INFO - Running setup.py install for blessings 02:14:06 INFO - Successfully installed blessings 02:14:06 INFO - Cleaning up... 02:14:06 INFO - Return code: 0 02:14:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:14:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:07 INFO - Reading from file tmpfile_stdout 02:14:07 INFO - Current package versions: 02:14:07 INFO - blessings == 1.6 02:14:07 INFO - blobuploader == 1.2.4 02:14:07 INFO - docopt == 0.6.1 02:14:07 INFO - functools32 == 3.2.3-2 02:14:07 INFO - jsonschema == 2.5.1 02:14:07 INFO - manifestparser == 1.1 02:14:07 INFO - mozInstall == 1.12 02:14:07 INFO - mozcrash == 1.0 02:14:07 INFO - mozdebug == 0.1 02:14:07 INFO - mozdevice == 0.49 02:14:07 INFO - mozfile == 1.2 02:14:07 INFO - mozhttpd == 0.7 02:14:07 INFO - mozinfo == 0.9 02:14:07 INFO - mozleak == 0.1 02:14:07 INFO - mozlog == 3.4 02:14:07 INFO - moznetwork == 0.27 02:14:07 INFO - mozprocess == 0.25 02:14:07 INFO - mozprofile == 0.28 02:14:07 INFO - mozrunner == 6.13 02:14:07 INFO - mozscreenshot == 0.1 02:14:07 INFO - mozsystemmonitor == 0.3 02:14:07 INFO - moztest == 0.8 02:14:07 INFO - mozversion == 1.4 02:14:07 INFO - psutil == 3.1.1 02:14:07 INFO - requests == 1.2.3 02:14:07 INFO - wsgiref == 0.1.2 02:14:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:07 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 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:07 INFO - 'HOME': '/Users/cltbld', 02:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:07 INFO - 'LOGNAME': 'cltbld', 02:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:07 INFO - 'MOZ_NO_REMOTE': '1', 02:14:07 INFO - 'NO_EM_RESTART': '1', 02:14:07 INFO - 'PAGER': '/bin/cat', 02:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:07 INFO - 'PWD': '/builds/slave/test', 02:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:07 INFO - 'SHELL': '/bin/bash', 02:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:14:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:07 INFO - 'USER': 'cltbld', 02:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:07 INFO - 'XPC_FLAGS': '0x0', 02:14:07 INFO - 'XPC_SERVICE_NAME': '0', 02:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:14:07 INFO - Cleaning up... 02:14:07 INFO - Return code: 0 02:14:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103257b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10325a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb589c9b4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, '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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:07 INFO - 'HOME': '/Users/cltbld', 02:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:07 INFO - 'LOGNAME': 'cltbld', 02:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:07 INFO - 'MOZ_NO_REMOTE': '1', 02:14:07 INFO - 'NO_EM_RESTART': '1', 02:14:07 INFO - 'PAGER': '/bin/cat', 02:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:07 INFO - 'PWD': '/builds/slave/test', 02:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:07 INFO - 'SHELL': '/bin/bash', 02:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:14:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:07 INFO - 'USER': 'cltbld', 02:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:07 INFO - 'XPC_FLAGS': '0x0', 02:14:07 INFO - 'XPC_SERVICE_NAME': '0', 02:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:14:07 INFO - Cleaning up... 02:14:07 INFO - Return code: 0 02:14:07 INFO - Running post-action listener: _resource_record_post_action 02:14:07 INFO - Running post-action listener: _start_resource_monitoring 02:14:07 INFO - Starting resource monitoring. 02:14:07 INFO - [mozharness: 2017-04-22 09:14:07.784556Z] Finished create-virtualenv step (success) 02:14:07 INFO - [mozharness: 2017-04-22 09:14:07.785500Z] Running install step. 02:14:07 INFO - Running pre-action listener: _resource_record_pre_action 02:14:07 INFO - Running main action method: install 02:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:08 INFO - Reading from file tmpfile_stdout 02:14:08 INFO - Detecting whether we're running mozinstall >=1.0... 02:14:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:14:08 INFO - Reading from file tmpfile_stdout 02:14:08 INFO - Output received: 02:14:08 INFO - Usage: mozinstall [options] installer 02:14:08 INFO - Options: 02:14:08 INFO - -h, --help show this help message and exit 02:14:08 INFO - -d DEST, --destination=DEST 02:14:08 INFO - Directory to install application into. [default: 02:14:08 INFO - "/builds/slave/test"] 02:14:08 INFO - --app=APP Application being installed. [default: firefox] 02:14:08 INFO - mkdir: /builds/slave/test/build/application 02:14:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:14:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:14:28 INFO - Reading from file tmpfile_stdout 02:14:28 INFO - Output received: 02:14:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:14:28 INFO - Running post-action listener: _resource_record_post_action 02:14:28 INFO - [mozharness: 2017-04-22 09:14:28.323818Z] Finished install step (success) 02:14:28 INFO - [mozharness: 2017-04-22 09:14:28.323946Z] Running run-tests step. 02:14:28 INFO - Running pre-action listener: _resource_record_pre_action 02:14:28 INFO - Running main action method: run_tests 02:14:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:14:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:14:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:14:28 INFO - 2017-04-22 02:14:28.337 screenresolution[1714:15572] starting screenresolution argv=screenresolution get 02:14:28 INFO - 2017-04-22 02:14:28.340 screenresolution[1714:15572] Display 0: 1600x1200x32@60 02:14:28 INFO - 2017-04-22 02:14:28.346 screenresolution[1715:15574] starting screenresolution argv=screenresolution list 02:14:28 INFO - Available Modes on Display 0 02:14:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:14:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:14:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:14:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:14:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:14:28 INFO - Intel Iris: 02:14:28 INFO - Chipset Model: Intel Iris 02:14:28 INFO - Type: GPU 02:14:28 INFO - Bus: Built-In 02:14:28 INFO - VRAM (Dynamic, Max): 1536 MB 02:14:28 INFO - Vendor: Intel (0x8086) 02:14:28 INFO - Device ID: 0x0a2e 02:14:28 INFO - Revision ID: 0x0009 02:14:28 INFO - Displays: 02:14:28 INFO - Display: 02:14:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:14:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:14:28 INFO - Display Serial Number: 1600x1200 60 02:14:28 INFO - Main Display: Yes 02:14:28 INFO - Mirror: Off 02:14:28 INFO - Online: Yes 02:14:28 INFO - Rotation: Supported 02:14:28 INFO - Return code: 0 02:14:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:14:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:14:28 INFO - Python 2.7.3 02:14:28 INFO - Return code: 0 02:14:28 INFO - grabbing minidump binary from tooltool 02:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:28 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 0x103035d30>, '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 0x102f5cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10302abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:14:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:14:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:14:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:14:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:14:28 INFO - Return code: 0 02:14:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:14:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:14:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:14:28 INFO - ENV: RUST_BACKTRACE is now 1 02:14:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:14:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:14:28 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/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:14:28 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/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:14:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vfZ1HFefWj/Render', 02:14:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:28 INFO - 'HOME': '/Users/cltbld', 02:14:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:28 INFO - 'LOGNAME': 'cltbld', 02:14:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:14:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:28 INFO - 'MOZ_NO_REMOTE': '1', 02:14:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:14:28 INFO - 'NO_EM_RESTART': '1', 02:14:28 INFO - 'PAGER': '/bin/cat', 02:14:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:28 INFO - 'PWD': '/builds/slave/test', 02:14:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:14:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:28 INFO - 'RUST_BACKTRACE': '1', 02:14:28 INFO - 'SHELL': '/bin/bash', 02:14:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bgvOQO4DYj/Listeners', 02:14:28 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 02:14:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:28 INFO - 'USER': 'cltbld', 02:14:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:28 INFO - 'XPC_FLAGS': '0x0', 02:14:28 INFO - 'XPC_SERVICE_NAME': '0', 02:14:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:28 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/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:14:28 INFO - mozversion application_buildid: 20170422004007 02:14:28 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:14:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:14:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:14:28 INFO - mozversion application_name: Firefox 02:14:28 INFO - mozversion application_remotingname: firefox-dev 02:14:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:14:28 INFO - mozversion application_vendor: Mozilla 02:14:28 INFO - mozversion application_version: 54.0a2 02:14:28 INFO - mozversion platform_buildid: 20170422004007 02:14:28 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:14:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:14:28 INFO - mozversion platform_version: 54.0a2 02:14:28 INFO - using testdate: 1492852468 02:14:28 INFO - actual date: 1492852468 02:14:28 INFO - starting webserver on 'localhost:49254' 02:14:28 INFO - SUITE-START | Running 2 tests 02:14:28 INFO - TEST-START | dromaeo_css 02:14:28 INFO - operating with platform_type : mac_ 02:14:28 INFO - Initialising browser for dromaeo_css test... 02:14:28 INFO - TEST-INFO | started process 1725 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpkxUUs_/profile http://localhost:49254/getInfo.html) 02:14:32 INFO - PROCESS | 1725 | 2017-04-22 02:14:32.152 plugin-container[1728:15759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9257, name = 'com.apple.tsm.portname' 02:14:32 INFO - PROCESS | 1725 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:32 INFO - PROCESS | 1725 | 2017-04-22 02:14:32.153 plugin-container[1728:15759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 02:14:32 INFO - PROCESS | 1725 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:32 INFO - PROCESS | 1725 | 2017-04-22 02:14:32.153 plugin-container[1728:15759] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:14:32 INFO - PROCESS | 1725 | __metrics Screen width/height:1600/1200 02:14:32 INFO - PROCESS | 1725 | colorDepth:24 02:14:32 INFO - PROCESS | 1725 | Browser inner width/height: 1024/689 02:14:32 INFO - PROCESS | 1725 | __metrics 02:14:32 ERROR - PROCESS | 1725 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:14:33 INFO - TEST-INFO | 1725: exit 0 02:14:33 INFO - Browser initialized. 02:14:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:14:33 INFO - TEST-INFO | started process 1730 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpkxUUs_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:14:35 INFO - PROCESS | 1730 | 2017-04-22 02:14:35.665 plugin-container[1731:15910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 02:14:35 INFO - PROCESS | 1730 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:35 INFO - PROCESS | 1730 | 2017-04-22 02:14:35.666 plugin-container[1731:15910] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 02:14:35 INFO - PROCESS | 1730 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:35 INFO - PROCESS | 1730 | 2017-04-22 02:14:35.666 plugin-container[1731:15910] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:14:45 INFO - PROCESS | 1730 | RSS: Main: 220696576 02:14:45 INFO - PROCESS | 1730 | 02:17:48 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49254/tests/dromaeo/cssquery-ext.html) 02:17:49 INFO - PROCESS | 1730 | RSS: Main: 239984640 02:17:49 INFO - PROCESS | 1730 | 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | A coding exception was thrown and uncaught in a Task. 02:19:33 INFO - PROCESS | 1730 | 02:19:33 ERROR - PROCESS | 1730 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:19:33 INFO - PROCESS | 1730 | Full stack: 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | A coding exception was thrown and uncaught in a Task. 02:19:33 INFO - PROCESS | 1730 | 02:19:33 ERROR - PROCESS | 1730 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:19:33 INFO - PROCESS | 1730 | Full stack: 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | A coding exception was thrown and uncaught in a Task. 02:19:33 INFO - PROCESS | 1730 | 02:19:33 ERROR - PROCESS | 1730 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:19:33 INFO - PROCESS | 1730 | Full stack: 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | ************************* 02:19:33 INFO - PROCESS | 1730 | A coding exception was thrown and uncaught in a Task. 02:19:33 INFO - PROCESS | 1730 | 02:19:33 ERROR - PROCESS | 1730 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:19:33 INFO - PROCESS | 1730 | Full stack: 02:19:33 INFO - PROCESS | 1730 | ************************* 02:20:52 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-ext.html (next: http://localhost:49254/tests/dromaeo/cssquery-jquery.html) 02:20:52 INFO - PROCESS | 1730 | RSS: Main: 249245696 02:20:52 INFO - PROCESS | 1730 | 02:23:54 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49254/tests/dromaeo/cssquery-mootools.html) 02:23:55 INFO - PROCESS | 1730 | RSS: Main: 256602112 02:23:55 INFO - PROCESS | 1730 | 02:26:57 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49254/tests/dromaeo/cssquery-prototype.html) 02:26:58 INFO - PROCESS | 1730 | RSS: Main: 253530112 02:26:58 INFO - PROCESS | 1730 | 02:30:00 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49254/tests/dromaeo/cssquery-yui.html) 02:30:01 INFO - PROCESS | 1730 | RSS: Main: 258355200 02:30:01 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | Cycle 1(1): loaded http://localhost:49254/tests/dromaeo/cssquery-yui.html (next: http://localhost:49254/tests/dromaeo/cssquery-dojo.html) 02:33:04 INFO - PROCESS | 1730 | RSS: Main: 254619648 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | __start_tp_report 02:33:04 INFO - PROCESS | 1730 | _x_x_mozilla_page_load 02:33:04 INFO - PROCESS | 1730 | _x_x_mozilla_page_load_details 02:33:04 INFO - PROCESS | 1730 | |i|pagename|runs| 02:33:04 INFO - PROCESS | 1730 | |0;dojo.html;3170.223295292654;3402.2174899773067;3467.2372078142807;3468.2023134679025;3471.2016236265645;4777.737224452662;5770.798022069229;5772.3361813391475;5862.2965244170655;5866.64800111993;5320.068987927107;5371.060064488718;5854.88290234195;5892.263467066617;5894.17481552583;5256.5794736421185;5335.866603334909;5351;5355.250264962909;5393.6763794172275;5407.270018547485;5418.376886658046;5418.756155972971;5430.8913821723545;5455.454454554552;5202.635815492917;5211.739413029352;5322.627416080876;5364.463553644624;5369.9463005369835;5332.280142180814;5339.145736682112;5391.865203369908;5419.891602167974;5456.809011684572;5283.55089817366;5335.759890804923;5341.706206158659;5343.198520221998;5356.758945847446;5510.972445137749;5813.854653633693;5925;5995.070764031582;6000.399960004008;5520.834374968757;5652.689102099424;6130.734471166763;6257.5619706620555;6267.247930248353;6335.493160547145;6349.746010159588;6365;6366.045093236052;6386.904196437057;6067.878642427127;6073.908891366677;6081.9929423290205;6099.146119543268;6114.755409783648;4587.357769912215;6215.223097112861;6273.96863015682;6401.839954001187;6421.678916054178;6141.946315431794;6177.320494745574;6239.719212635442;6253.7185826637915;6256.874862502725;5507.394186639466;6291.056341548713;6316;6346.492280617539;6361.363863613602;6270.529710271657;6301.117843501914;6318.526110541636;6332.810015699536;6352.237731472161;4994.675346102492;6160.322364539897;6217.098520714471;6217.595856269328;6255.593386429959;4492.415985921874;5091.719955402488;5097.413797413345;5100.387953445536;5111.591072714174;3350.2964377480516;4340.65274778017;4540.77296135192;4555.521670224645;4557.384753058294;5681.659100453986;5731.484166425042;5887.35239123696;5950.16697662319;5989.700514974234;6319.747210111544;6364.64994425311;6388.84027899297;6403.519736019817;6419.967900160563;6000.219971403777;6025.186599809057;6025.548083893726;6031.788887389008;6053.636781793019;5408.350997880201;5467.835964921059;5663.631863928831;5665.065264231438;5673.035583950656;6322.841928951721;6487.675616219264;6518.087467754613;6541.836454088686;6559.6064236145985;6303.9684801575695;6348.492120630431;6379.681015949277;6391.776287829904;6400.55196136266;4867.561919427127;5506.311711036121;5580.135079062589;6471.579347342407;6521.478281737544;6123.1606109212;6179.536534760001;6244;6257.217847769029;6265.060240963892;6313.273973493107;6326.304106548184;6328.335524769833;6331.366863313816;6335.018072198816;5021.271915572294;5029.84910452687;5418.07892658257;6179.351168127282;6254.906176407449;6201.875962480815;6275.152854364603;6297.685115744286;6304.05439184126;6347.746090156526;5794.985877471341;5951.7024148791525;6102.298235702951;6107.816765497042;6112.816615501542;6213.65824879636;6221.160143380585;6272.937270627418;6280.874382512416;6292.590981586182;6126.846828829315;6129.570930034854;6137.754489820357;6154.446099851035;6211.937880621136 02:33:04 INFO - PROCESS | 1730 | |1;ext.html;797.660994077517;799.5242830515841;818.6193420059673;937.3391758810039;966.4056605187811;37008.81495592522;38954.61045389545;38986;39071.60928390715;39182;37516.624833751725;37776.81111594445;37911.431328530096;38018.479260829605;38159.42760858582;7807.609619519029;10391.584336626525;10673;10856.674299771019;10872;10581.894181058206;10590.993855583745;10646.946765266162;10663.733406664818;10671.19966002549;10666.253362264644;10676.839847402296;10686.572537098507;10691.411972341519;10706.892931070668;10630.62792802252;10631.255812093157;10648.57405703771;10649.361038337725;10671.626493072705;10414.479276036167;10415.635452759192;10478.266521343508;10503;10504.684859454228;32637.6736232637;32877.50683739746;32880;32910.83544582286;33106.6689333106;3646.9452958205648;3675.4119340905327;3698.7225958052986;3720.7395482316247;3759.2669429461266;24432.877835610885;24484.14305499317;24777.380565485826;25877.094301699537;25883.611745823826;3046.923826904323;3066.540018997146;3157.605299337583;3228.790128641631;3263.4288999425244;340264;363048;364033;364317;365347.9999999973;35726.821365893;35853.82073089618;51534.74232628813;52642.73678631582;52742;43253.78373108177;53020.20469692958;55697;55825;55909;12292.201006934522;12440.75118497643;15093.622659433602;15120.54638360851;15188.620284492976;11443.542258309575;12700.237985720887;12903.935480322538;12994.610161695164;13024.218546887218;11002;13148.07963442569;13331.400231972226;13334.133281336683;13361.53234636783;4572.702774319659;5400.83797486076;5939.762409503572;6002.54980876436;6015.368386319461;1058.222206678091;1136.8976792088586;1138.9031932285682;1144.753877916252;1175.976480470403;21902.890485547472;26191.476170476864;26228.73771262301;26283.211503654922;26512.337191570365;1001.3991605037065;1047.7275908263907;1080.56777289085;1101.3557069114497;1197.3294954825326;11017.889821101846;12568.120231583702;12986;13044.739105217843;13250.204987700768;495406;498228;498566;500505;500615;43915.56084439179;44298;44389.33415998763;44400.3339949901;44428.3335749964;1381.785823197406;1415.1084816565703;1415.3418660323;1420.4176287721984;1424.2949739878827;24192.206316859996;24604.138855139983;24935.251942441755;24962.875185623954;25036.62445063362;37416.62583374132;37756.43365349467;37781.4332785014;37844.43233351557;37918;481769;485779;487355;487704;489865;28828.567571486863;29797.5530367049;30340.84829575838;31344.05967820969;31415.685843141277;37645.81177094097;37795;38518;38527.6147238524;38843.80578097091;27101.1869643911;27125.86437067802;27160.592591111545;27487.587686184324;28227;22802.315930522112;27442.313942151606;27546;27733.44533109367;28267.71732282733;31709.682903170673;31918.52122218122;32183.873564425132;32238.838805805823;32320.35359292754;27257;27659.170224893285;27776;28515.28711782222;28775.71224287814;19939.401817945483;21258.7874121263;21433.78566214318;22404.759524047466;22584.5483090334 02:33:04 INFO - PROCESS | 1730 | |2;jquery.html;380.2433158015549;408.20807633191606;410.40696193999685;419.89292730353753;451.494326354483;19590.804091959075;19936.90031549842;20272.797272027274;20513.692294615576;20754.792452075475;17855.73216401755;21246.57506849866;21542;21558.568828623418;21877.453063673416;5778.711064446782;6360.713767880433;6539.901901471481;6554.311797261291;6575.210974683021;5231.555317797992;6288.811335659914;6502.642354670491;6519.739210431577;6610.768623098193;5663.405342439047;6513.739450422001;6523.151990241262;6528.281888992207;6544.836379090537;6298.748050077991;6437.776822403728;6502.577332473374;6524.086627872054;6526.445252153572;5558.777069044803;5890.823275301705;6303.212098487689;6334.208223972004;6391.8402039948905;25230.747692523022;25386.11148609808;25583.360415989562;25617.615735763793;26113.43319400842;2864.1264414353614;2864.799464037518;2870.7846911481724;2877.4820532304175;2889.8266104033823;15844.841551584566;16213.594660133473;16684.416045438353;16704.916475417667;17205.91397043007;1806.3948577226645;2013.234970711135;2098.7586427560873;2113.4505028692497;2123;126408.10387844189;134840.65159348378;135254.6474535252;137097;137572.31213843866;17579.033153176697;20354.592908141753;21988.450288742908;22030.779692203192;22309.442263943532;34885.30229395398;35372;35800.462993055124;35808.28383432317;35836.46245306323;9906.455144966953;10117.62235874569;10875.891241087646;10924.344539327665;11247.887521124683;8916.777080573038;8917.910820891837;8951.910480895238;9135.54322283883;9146.359754817098;10086.546105425274;10421.68734937953;10512.947435262928;10514.894851051544;10517.9474102629;3755.7183211259266;4334.393184954109;4437.356583295404;4464.888377790517;4488.775561221991;1095.9342439453499;1097.8353247013013;1101.1906248907046;1101.515333253366;1148.9942550287362;21253.36239912805;21455;22242.443938901655;22324.330270091923;22489.887550562144;900.7297810656777;947.7962238118838;979.4270351844109;982.2878413150409;1008.7730260691286;8279.793005174919;9998.700038998697;10592.470376481146;10708;10757.677269681923;779646.1017694989;784986;786322.0683896658;790714;796856;116618;133866.99999999805;169050;169258.15370923313;170421.29578704303;1018.994905025485;1240.5430265204543;1276.9936150319188;1282.6344491819787;1282.7562763074986;15822.68354632924;18751.718724218874;19360.903195483934;19466.707999379738;20153.193872244945;49555.18057372226;62521;64220.43118275217;66185.33814661985;67591.32408676048;69918;95926;96037.5198124005;98026;98106.50946745506;10804.189685773441;10978.011978921935;11168.497417616065;11279.7744045117;11504.712382190512;12024.33866137371;12073.85298396651;12294.815577766161;12430.81353779676;12481.313527756061;8627.482351058956;10581.576736930745;10697.572097116028;10713.35719856811;10979.231453798477;8823.955880220557;9128.49793261377;9823.21414286841;9943.950280248553;9974.60101595957;10326.741831453974;10968.067714244378;11020.44897755093;11112.716690999428;11242.426060351721;10907.618233361794;11049.33703977764;11150.665480035612;11208.83186752183;11232.719182020515;10672.8932710675;10688.305260158066;10865.945670271598;10938.343699377745;10971.341719496855 02:33:04 INFO - PROCESS | 1730 | |3;mootools.html;1453.7019910918264;1485.0718301061836;1523.1394262241834;1552.1451865021297;1556.1907807939872;10485.633002844901;10881.23831331807;11150.275232109918;11161.162912781543;11231.719207019829;10954.342739435639;11134.554617815298;11151.776964460705;11163.888361116386;11172.720681982955;1996.331229038274;2260.683504309394;2342.1919437793936;2355.5171189906114;2356.045801450415;2288.1762565476415;2345.4605440748637;2362.303120579433;2364.1016413762745;2366.8993917828207;2316.9110518056573;2341.1454818991033;2344.6248600223967;2366.325597204797;2369.8104151667912;2290.095412312144;2304.066852924568;2344.636581329888;2352.5882970480097;2352.8588284702887;2336.9649455258186;2340.2979106268053;2343.906243750248;2344.308429013445;2351.130081869702;11068.837772033901;11120.110391168768;11131.499082541304;11202.103831693445;11240.887591124148;5502.779888804443;5668.234788303567;5671;5676.3188417389765;5683.005474042067;3314.5193946877807;3428.6057103432927;3561.9109522261892;3578.785272883635;3606;1101.372217835834;1119.3060302612432;1171.7363593191549;1179.4633441784054;1186.0748616079472;13421.463141474329;13601.387937542835;14525.999999999894;15217.391304347813;15252.08487490743;4499.68502204849;4612.285095810154;4619.376384188159;4625.861224163281;4626.421697287839;10729;11585;11679.416029198506;11732;11760.000399966098;4711.575958163782;5545.861353466115;5562.860928476821;5609.186667933209;5614.887702245932;5002.824901128443;5117.41149767774;5163.741812909339;5174.689518628894;5176.068307704574;3681.687056600219;3814.5422549293708;3819.1788765415567;3848.191879705293;3912.061105334756;907.6278725722555;915.2815040193416;970.3498655900563;970.9174720148867;976.3165783951263;917.936478795661;928.8374534456443;935.7286386947845;935.8830146231721;977.3988996766938;5458.426865179178;5544.528715059265;5609.326880774252;6279.560430769802;6301.747930082837;849.6771226933726;984.17329443268;1004.3572113846998;1010.7927874785652;1016.5527168046036;4308.418363520885;4325.978370108192;4393.868183954486;4415.690901636919;4442.244818380883;13017.869821301856;13236.735265294641;13248.536301229411;13540.390682419315;13585.320733963263;13329.133606315558;13331.933340333237;13474.393652285671;13495.797563036562;13519.932400337935;10103.696889093339;10106.292559520763;10117.848232276374;10223.335483193569;10234.795304093876;6456.999165129412;6494.155759751202;6519.967400162969;6587.769428069994;6778.559393639398;11760.941195293968;11973.580924667594;12116.515339386326;12164.939175304067;12237.326947018;12218.26690398579;12267.815982760445;12324;12398.5660501882;12443.191192572454;8313.043999940084;8495.277901378206;8495.95752021236;8499.702510412106;8505.74482765518;9876.85184722244;10120;10206.948965255424;10212.336198147097;10219.948900255451;8587.527685977331;8730.69442569507;8760.868586971328;8766.254868336262;8904.332175087024;8100.797480063045;8536.274416674403;8570.314374849871;8636.481811091355;8755.343349248958;7663.578503182379;7899.644515996909;8107.4324797263625;8115.59422028908;8158.959205203936;4758.262469317261;5836.737346819318;5900.203472531153;5981.431763982415;6132.172156758959;5938.10928360732;5983.401659833982;6007.429294217042;6062.818115456544;6149.815505534841 02:33:04 INFO - PROCESS | 1730 | |4;prototype.html;366.38081642024974;371.8902923637527;373.3913720635364;384.7768294389253;385.8726620215329;4574.879154607126;4705.64707646927;4708.552687494687;4981.651284410093;5211.296474975878;4361.454818147731;4520.095980803844;4764.5235476452335;4856.878578035551;5233.65981211221;4170.955202702029;4344.934825977605;4365.607095361417;4371.3661519079715;4389.429374181368;3707.516993202727;3852.9807350963206;4298.905796631002;4317.12205190743;4385.26781921141;3563.465480177968;3651.3427583035045;3878.1772566893515;4388.736675799462;4396.648268138557;3995.94006089906;4047.494063242095;4244.257254980397;4295.355696645513;4299.892502687427;3299.3566254580364;3484.9545136459215;3564.269324788438;3983.601639836022;3987.1029018470904;4467.917137175338;5078.886102257184;5091.9236211456855;5107.106256405114;5121.385433747974;4180.91638167238;4736.905261894777;4994.07609592223;4996.400431948153;5079.949200507985;4628;5554.583406244547;5580.414056524047;5598.776048958037;5618.213450116987;3821.8853434396733;4383.956160438419;4439.800208990603;4517.932231016504;4557.9772101139615;5155.226715992593;5309.65487243328;5596.4123767004285;5970.522358211333;6030.728617212236;4219.07180420307;4310.54739252374;4515.638748900145;4538.364628951949;4542.795574199103;4188.01069491995;4623.583877451046;4651.023285110165;4672.813087476531;4734.4081989751285;4251.149394000871;4481.865544033684;4991.6306493117;5026.346574945307;5043.495650435;4812.759362031885;5393.5954803389905;5509.449055094458;5516.668999859941;5545.223668686387;1269.9428525716364;1387.1273780396384;1546.4200924653255;1553.829078801331;1562.742147545665;958.9355815045294;1059.5814653212092;1126.7859106769688;1182.5565412970136;1214.8094867030359;4604.815807367668;4676.298555216745;4987.426445958761;5252.369715634149;5253.290805741252;5281.287026251407;5790.971045144747;5881.764729410861;5899.262592175978;5930.525557955353;4716.764161791897;4798.352222449925;4842.878928026827;5208.583313334924;5235.947640523622;2564.743525647458;3001.904304928685;3029.363833594926;3035.5598438226775;3069.723724864773;5790.739416726257;6007.068904319837;6023.427774361428;6043.2748070231855;6074.301455332693;4393.846215382447;5428.511433970962;5628.521575666033;5848.385919478394;5874.50066744323;4542.5911667949385;5698.344690360579;5992;6044.607100538451;6069.332373438918;5076.82698313554;5104.438511763628;5109.948900510947;5354.518093371616;5414.404415514212;5176.2494438306985;5277.604179686616;5469.726513674221;5659.320881494247;5805.448482394166;5761.193432919311;5897.967855625334;6101.60339577926;6107.755689772359;6116.571839971337;4154.459920210354;4178.5318185906235;4356.128774245227;4671.85984420468;4682.765861706969;5399.2980912481125;5479.178223214968;5494.80768173112;5666.336540972625;5720.3421606515785;5016.6237532184505;5081.568066714222;5442.591805614516;5521.944780552143;5644.266245388073;5240.34495688039;5471.890562188813;5491.615586908878;5525.336959564878;5587.636803607753;4448.310511870665;4574.473935497461;4614.861554153381;5388.38033626138;5441.673499590037;5256;5523.309586301712;5531.976584331912;5578.804741834017;5610.4670056344585;4624.768761561976;5065.366829146356;5328.333958255218;5364.678119312854;5472.452754724496 02:33:04 INFO - PROCESS | 1730 | |5;yui.html;729.165105953683;732.3079689693235;745.7091734223653;825.0841565861895;834.8079941613427;15311.54065378039;15809.525714228563;15941;16324.673506529862;16385.918070409647;13003.349832508384;15549.300281487333;15695.529134125965;15742.134182619951;15748.370065197407;765.9923400766005;893.0578239956843;942.4439580647417;946.1768261612373;950.6340059077236;912.7261821453537;925.2366797392144;926.3098991251493;927.5686805635378;928.7724507495673;1004.5128112865291;1005.1857995024054;1008.1682611845221;1008.8587597736323;1011.1405305490312;920.2408013388947;988.1452543549777;989.3025417080977;1001.444198469849;1005.4118340770658;735.51456039014;745.4744405194325;746.4177941205868;746.436440487434;747.7943565519471;16621.750673739905;16671;16866.241019154168;16925.23836427364;17257.223424945907;13871.729696527787;13895.722085558233;13911.373988170557;14078.929605351908;14139.929300353535;11790.764184716343;11818.231814932002;11892.286462812259;11893.286402815773;11919.821202681967;3550.3254381667402;3607.963920360789;3611.3138503684218;3648.616895226016;3759.7368184227116;93089;114434.42782786116;115809.5257142287;116503;117482;10836.620718274822;10906.890931090746;10916.617918372978;10981.835272470918;11207.887921120848;22456.775432245795;22717.432064198198;22740;22767.658485122738;22797.772022279896;7638.388928885676;7691.077070751434;7719.575423351657;7721.266479684421;7959.522428654299;4137.420761093449;4175.185838761412;4227.8097485613225;4238.279492486285;4283.271843786501;3389.5593572835373;3454.8790792322147;3518.806465644363;3532.788032718045;3552.2540266544315;627.137685682187;628.8176428835586;630.8422894276431;635.5011316116919;643.5848877474003;666.3802663522873;666.4335314982324;689.4587748617312;691.4675699711195;697.616311028932;9822.263330250116;10356.067953884187;10709.357438553712;10883.891161088288;11372.886271137348;571.6312978129083;585.9209006784115;591.5622439394817;595.592019466658;710.4281053751677;4846.903061938741;5027.547520723152;5059.924101138486;5066.36670416198;5078.4413714491375;87777;87921;88647;90130;91288.08711912928;79666;82431.58784206041;83004;83939.58030209932;84710.5764471186;60032.09951850726;65002.374940625916;66794;75536.24463755293;75908.99151144085;8388;10731.443940628722;10884.292520986428;11319.830202546804;11386.658400248005;47382.28926566035;47424.81437964078;48144.55566333015;49459.258111127645;49967;68182;70301.64849175721;72625;72690.27309726835;72957;12356.443960022001;12396.256224626191;12564.811527827273;12774.744505110031;12929.54746583865;14434;14509;14515.419383224553;14821.40714371413;14934;13419.731605368033;13605.183688978694;13896.51362202318;14379.065360751518;14416.927915360357;13229.735405292033;13671.17972921628;13678.863211368158;13687.589372318846;13703.588892333246;9848.901510985084;11996.400179990791;12069.698257543281;12373.75252494927;12700.87299126997;9776.853347199656;10194.490275486345;10203;10344.793104138025;10354.689359319233;10090;11274;11331.47025151594;11461.369624670722;11555.768884622092 02:33:04 INFO - PROCESS | 1730 | __end_tp_report 02:33:04 INFO - PROCESS | 1730 | __start_cc_report 02:33:04 INFO - PROCESS | 1730 | _x_x_mozilla_cycle_collect,427 02:33:04 INFO - PROCESS | 1730 | __end_cc_report 02:33:04 INFO - PROCESS | 1730 | __startTimestamp1492853584568__endTimestamp 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | ------- Summary: start ------- 02:33:04 INFO - PROCESS | 1730 | Number of tests: 6 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#0] dojo.html Cycles:165 Average:5784.97 Median:6053.64 stddev:687.55 (11.4%) stddev-sans-first:658.35 02:33:04 INFO - PROCESS | 1730 | Values: 3170.2 3402.2 3467.2 3468.2 3471.2 4777.7 5770.8 5772.3 5862.3 5866.6 5320.1 5371.1 5854.9 5892.3 5894.2 5256.6 5335.9 5351.0 5355.3 5393.7 5407.3 5418.4 5418.8 5430.9 5455.5 5202.6 5211.7 5322.6 5364.5 5369.9 5332.3 5339.1 5391.9 5419.9 5456.8 5283.6 5335.8 5341.7 5343.2 5356.8 5511.0 5813.9 5925.0 5995.1 6000.4 5520.8 5652.7 6130.7 6257.6 6267.2 6335.5 6349.7 6365.0 6366.0 6386.9 6067.9 6073.9 6082.0 6099.1 6114.8 4587.4 6215.2 6274.0 6401.8 6421.7 6141.9 6177.3 6239.7 6253.7 6256.9 5507.4 6291.1 6316.0 6346.5 6361.4 6270.5 6301.1 6318.5 6332.8 6352.2 4994.7 6160.3 6217.1 6217.6 6255.6 4492.4 5091.7 5097.4 5100.4 5111.6 3350.3 4340.7 4540.8 4555.5 4557.4 5681.7 5731.5 5887.4 5950.2 5989.7 6319.7 6364.6 6388.8 6403.5 6420.0 6000.2 6025.2 6025.5 6031.8 6053.6 5408.4 5467.8 5663.6 5665.1 5673.0 6322.8 6487.7 6518.1 6541.8 6559.6 6304.0 6348.5 6379.7 6391.8 6400.6 4867.6 5506.3 5580.1 6471.6 6521.5 6123.2 6179.5 6244.0 6257.2 6265.1 6313.3 6326.3 6328.3 6331.4 6335.0 5021.3 5029.8 5418.1 6179.4 6254.9 6201.9 6275.2 6297.7 6304.1 6347.7 5795.0 5951.7 6102.3 6107.8 6112.8 6213.7 6221.2 6272.9 6280.9 6292.6 6126.8 6129.6 6137.8 6154.4 6211.9 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#1] ext.html Cycles:180 Average:56544.55 Median:28247.36 stddev:120658.36 (427.1%) stddev-sans-first:120924.24 02:33:04 INFO - PROCESS | 1730 | Values: 797.7 799.5 818.6 937.3 966.4 37008.8 38954.6 38986.0 39071.6 39182.0 37516.6 37776.8 37911.4 38018.5 38159.4 7807.6 10391.6 10673.0 10856.7 10872.0 10581.9 10591.0 10646.9 10663.7 10671.2 10666.3 10676.8 10686.6 10691.4 10706.9 10630.6 10631.3 10648.6 10649.4 10671.6 10414.5 10415.6 10478.3 10503.0 10504.7 32637.7 32877.5 32880.0 32910.8 33106.7 3646.9 3675.4 3698.7 3720.7 3759.3 24432.9 24484.1 24777.4 25877.1 25883.6 3046.9 3066.5 3157.6 3228.8 3263.4 340264.0 363048.0 364033.0 364317.0 365348.0 35726.8 35853.8 51534.7 52642.7 52742.0 43253.8 53020.2 55697.0 55825.0 55909.0 12292.2 12440.8 15093.6 15120.5 15188.6 11443.5 12700.2 12903.9 12994.6 13024.2 11002.0 13148.1 13331.4 13334.1 13361.5 4572.7 5400.8 5939.8 6002.5 6015.4 1058.2 1136.9 1138.9 1144.8 1176.0 21902.9 26191.5 26228.7 26283.2 26512.3 1001.4 1047.7 1080.6 1101.4 1197.3 11017.9 12568.1 12986.0 13044.7 13250.2 495406.0 498228.0 498566.0 500505.0 500615.0 43915.6 44298.0 44389.3 44400.3 44428.3 1381.8 1415.1 1415.3 1420.4 1424.3 24192.2 24604.1 24935.3 24962.9 25036.6 37416.6 37756.4 37781.4 37844.4 37918.0 481769.0 485779.0 487355.0 487704.0 489865.0 28828.6 29797.6 30340.8 31344.1 31415.7 37645.8 37795.0 38518.0 38527.6 38843.8 27101.2 27125.9 27160.6 27487.6 28227.0 22802.3 27442.3 27546.0 27733.4 28267.7 31709.7 31918.5 32183.9 32238.8 32320.4 27257.0 27659.2 27776.0 28515.3 28775.7 19939.4 21258.8 21433.8 22404.8 22584.5 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#2] jquery.html Cycles:180 Average:43562.69 Median:21932.95 stddev:130744.08 (596.1%) stddev-sans-first:131070.64 02:33:04 INFO - PROCESS | 1730 | Values: 380.2 408.2 410.4 419.9 451.5 19590.8 19936.9 20272.8 20513.7 20754.8 17855.7 21246.6 21542.0 21558.6 21877.5 5778.7 6360.7 6539.9 6554.3 6575.2 5231.6 6288.8 6502.6 6519.7 6610.8 5663.4 6513.7 6523.2 6528.3 6544.8 6298.7 6437.8 6502.6 6524.1 6526.4 5558.8 5890.8 6303.2 6334.2 6391.8 25230.7 25386.1 25583.4 25617.6 26113.4 2864.1 2864.8 2870.8 2877.5 2889.8 15844.8 16213.6 16684.4 16704.9 17205.9 1806.4 2013.2 2098.8 2113.5 2123.0 126408.1 134840.7 135254.6 137097.0 137572.3 17579.0 20354.6 21988.5 22030.8 22309.4 34885.3 35372.0 35800.5 35808.3 35836.5 9906.5 10117.6 10875.9 10924.3 11247.9 8916.8 8917.9 8951.9 9135.5 9146.4 10086.5 10421.7 10512.9 10514.9 10517.9 3755.7 4334.4 4437.4 4464.9 4488.8 1095.9 1097.8 1101.2 1101.5 1149.0 21253.4 21455.0 22242.4 22324.3 22489.9 900.7 947.8 979.4 982.3 1008.8 8279.8 9998.7 10592.5 10708.0 10757.7 779646.1 784986.0 786322.1 790714.0 796856.0 116618.0 133867.0 169050.0 169258.2 170421.3 1019.0 1240.5 1277.0 1282.6 1282.8 15822.7 18751.7 19360.9 19466.7 20153.2 49555.2 62521.0 64220.4 66185.3 67591.3 69918.0 95926.0 96037.5 98026.0 98106.5 10804.2 10978.0 11168.5 11279.8 11504.7 12024.3 12073.9 12294.8 12430.8 12481.3 8627.5 10581.6 10697.6 10713.4 10979.2 8824.0 9128.5 9823.2 9944.0 9974.6 10326.7 10968.1 11020.4 11112.7 11242.4 10907.6 11049.3 11150.7 11208.8 11232.7 10672.9 10688.3 10865.9 10938.3 10971.3 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#3] mootools.html Cycles:180 Average:6612.95 Median:2368.35 stddev:4145.19 (175.0%) stddev-sans-first:4138.69 02:33:04 INFO - PROCESS | 1730 | Values: 1453.7 1485.1 1523.1 1552.1 1556.2 10485.6 10881.2 11150.3 11161.2 11231.7 10954.3 11134.6 11151.8 11163.9 11172.7 1996.3 2260.7 2342.2 2355.5 2356.0 2288.2 2345.5 2362.3 2364.1 2366.9 2316.9 2341.1 2344.6 2366.3 2369.8 2290.1 2304.1 2344.6 2352.6 2352.9 2337.0 2340.3 2343.9 2344.3 2351.1 11068.8 11120.1 11131.5 11202.1 11240.9 5502.8 5668.2 5671.0 5676.3 5683.0 3314.5 3428.6 3561.9 3578.8 3606.0 1101.4 1119.3 1171.7 1179.5 1186.1 13421.5 13601.4 14526.0 15217.4 15252.1 4499.7 4612.3 4619.4 4625.9 4626.4 10729.0 11585.0 11679.4 11732.0 11760.0 4711.6 5545.9 5562.9 5609.2 5614.9 5002.8 5117.4 5163.7 5174.7 5176.1 3681.7 3814.5 3819.2 3848.2 3912.1 907.6 915.3 970.3 970.9 976.3 917.9 928.8 935.7 935.9 977.4 5458.4 5544.5 5609.3 6279.6 6301.7 849.7 984.2 1004.4 1010.8 1016.6 4308.4 4326.0 4393.9 4415.7 4442.2 13017.9 13236.7 13248.5 13540.4 13585.3 13329.1 13331.9 13474.4 13495.8 13519.9 10103.7 10106.3 10117.8 10223.3 10234.8 6457.0 6494.2 6520.0 6587.8 6778.6 11760.9 11973.6 12116.5 12164.9 12237.3 12218.3 12267.8 12324.0 12398.6 12443.2 8313.0 8495.3 8496.0 8499.7 8505.7 9876.9 10120.0 10206.9 10212.3 10219.9 8587.5 8730.7 8760.9 8766.3 8904.3 8100.8 8536.3 8570.3 8636.5 8755.3 7663.6 7899.6 8107.4 8115.6 8159.0 4758.3 5836.7 5900.2 5981.4 6132.2 5938.1 5983.4 6007.4 6062.8 6149.8 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#4] prototype.html Cycles:180 Average:4604.13 Median:4989.53 stddev:1310.80 (26.3%) stddev-sans-first:1275.30 02:33:04 INFO - PROCESS | 1730 | Values: 366.4 371.9 373.4 384.8 385.9 4574.9 4705.6 4708.6 4981.7 5211.3 4361.5 4520.1 4764.5 4856.9 5233.7 4171.0 4344.9 4365.6 4371.4 4389.4 3707.5 3853.0 4298.9 4317.1 4385.3 3563.5 3651.3 3878.2 4388.7 4396.6 3995.9 4047.5 4244.3 4295.4 4299.9 3299.4 3485.0 3564.3 3983.6 3987.1 4467.9 5078.9 5091.9 5107.1 5121.4 4180.9 4736.9 4994.1 4996.4 5079.9 4628.0 5554.6 5580.4 5598.8 5618.2 3821.9 4384.0 4439.8 4517.9 4558.0 5155.2 5309.7 5596.4 5970.5 6030.7 4219.1 4310.5 4515.6 4538.4 4542.8 4188.0 4623.6 4651.0 4672.8 4734.4 4251.1 4481.9 4991.6 5026.3 5043.5 4812.8 5393.6 5509.4 5516.7 5545.2 1269.9 1387.1 1546.4 1553.8 1562.7 958.9 1059.6 1126.8 1182.6 1214.8 4604.8 4676.3 4987.4 5252.4 5253.3 5281.3 5791.0 5881.8 5899.3 5930.5 4716.8 4798.4 4842.9 5208.6 5235.9 2564.7 3001.9 3029.4 3035.6 3069.7 5790.7 6007.1 6023.4 6043.3 6074.3 4393.8 5428.5 5628.5 5848.4 5874.5 4542.6 5698.3 5992.0 6044.6 6069.3 5076.8 5104.4 5109.9 5354.5 5414.4 5176.2 5277.6 5469.7 5659.3 5805.4 5761.2 5898.0 6101.6 6107.8 6116.6 4154.5 4178.5 4356.1 4671.9 4682.8 5399.3 5479.2 5494.8 5666.3 5720.3 5016.6 5081.6 5442.6 5521.9 5644.3 5240.3 5471.9 5491.6 5525.3 5587.6 4448.3 4574.5 4614.9 5388.4 5441.7 5256.0 5523.3 5532.0 5578.8 5610.5 4624.8 5065.4 5328.3 5364.7 5472.5 02:33:04 INFO - PROCESS | 1730 | 02:33:04 INFO - PROCESS | 1730 | [#5] yui.html Cycles:180 Average:19993.16 Median:3541.56 stddev:28135.57 (794.4%) stddev-sans-first:28177.31 02:33:04 INFO - PROCESS | 1730 | Values: 729.2 732.3 745.7 825.1 834.8 15311.5 15809.5 15941.0 16324.7 16385.9 13003.3 15549.3 15695.5 15742.1 15748.4 766.0 893.1 942.4 946.2 950.6 912.7 925.2 926.3 927.6 928.8 1004.5 1005.2 1008.2 1008.9 1011.1 920.2 988.1 989.3 1001.4 1005.4 735.5 745.5 746.4 746.4 747.8 16621.8 16671.0 16866.2 16925.2 17257.2 13871.7 13895.7 13911.4 14078.9 14139.9 11790.8 11818.2 11892.3 11893.3 11919.8 3550.3 3608.0 3611.3 3648.6 3759.7 93089.0 114434.4 115809.5 116503.0 117482.0 10836.6 10906.9 10916.6 10981.8 11207.9 22456.8 22717.4 22740.0 22767.7 22797.8 7638.4 7691.1 7719.6 7721.3 7959.5 4137.4 4175.2 4227.8 4238.3 4283.3 3389.6 3454.9 3518.8 3532.8 3552.3 627.1 628.8 630.8 635.5 643.6 666.4 666.4 689.5 691.5 697.6 9822.3 10356.1 10709.4 10883.9 11372.9 571.6 585.9 591.6 595.6 710.4 4846.9 5027.5 5059.9 5066.4 5078.4 87777.0 87921.0 88647.0 90130.0 91288.1 79666.0 82431.6 83004.0 83939.6 84710.6 60032.1 65002.4 66794.0 75536.2 75909.0 8388.0 10731.4 10884.3 11319.8 11386.7 47382.3 47424.8 48144.6 49459.3 49967.0 68182.0 70301.6 72625.0 72690.3 72957.0 12356.4 12396.3 12564.8 12774.7 12929.5 14434.0 14509.0 14515.4 14821.4 14934.0 13419.7 13605.2 13896.5 14379.1 14416.9 13229.7 13671.2 13678.9 13687.6 13703.6 9848.9 11996.4 12069.7 12373.8 12700.9 9776.9 10194.5 10203.0 10344.8 10354.7 10090.0 11274.0 11331.5 11461.4 11555.8 02:33:04 INFO - PROCESS | 1730 | -------- Summary: end -------- 02:33:04 INFO - PROCESS | 1730 | 02:33:05 INFO - TEST-INFO | 1730: exit 0 02:33:05 INFO - TEST-OK | dromaeo_css | took 1116298ms 02:33:05 INFO - TEST-START | kraken 02:33:05 INFO - operating with platform_type : mac_ 02:33:05 INFO - Initialising browser for kraken test... 02:33:05 INFO - TEST-INFO | started process 1739 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpUdIPFM/profile http://localhost:49254/getInfo.html) 02:33:08 INFO - PROCESS | 1739 | 2017-04-22 02:33:08.196 plugin-container[1741:18311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 02:33:08 INFO - PROCESS | 1739 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:08 INFO - PROCESS | 1739 | 2017-04-22 02:33:08.197 plugin-container[1741:18311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 02:33:08 INFO - PROCESS | 1739 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:08 INFO - PROCESS | 1739 | 2017-04-22 02:33:08.197 plugin-container[1741:18311] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:33:08 INFO - PROCESS | 1739 | __metrics Screen width/height:1600/1200 02:33:08 INFO - PROCESS | 1739 | colorDepth:24 02:33:08 INFO - PROCESS | 1739 | Browser inner width/height: 1024/689 02:33:08 INFO - PROCESS | 1739 | __metrics 02:33:08 ERROR - PROCESS | 1739 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:33:09 INFO - TEST-INFO | 1739: exit 0 02:33:09 INFO - Browser initialized. 02:33:09 INFO - Running cycle 1/1 for kraken test... 02:33:09 INFO - TEST-INFO | started process 1742 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpUdIPFM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:11 INFO - PROCESS | 1742 | 2017-04-22 02:33:11.448 plugin-container[1743:18444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 02:33:11 INFO - PROCESS | 1742 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:11 INFO - PROCESS | 1742 | 2017-04-22 02:33:11.449 plugin-container[1743:18444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:33:11 INFO - PROCESS | 1742 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:11 INFO - PROCESS | 1742 | 2017-04-22 02:33:11.449 plugin-container[1743:18444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:33:21 INFO - PROCESS | 1742 | RSS: Main: 220700672 02:33:21 INFO - PROCESS | 1742 | 02:33:24 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection) 02:33:25 INFO - PROCESS | 1742 | RSS: Main: 235380736 02:33:25 INFO - PROCESS | 1742 | 02:33:27 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-dft) 02:33:28 INFO - PROCESS | 1742 | RSS: Main: 243294208 02:33:28 INFO - PROCESS | 1742 | 02:33:31 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-fft) 02:33:32 INFO - PROCESS | 1742 | RSS: Main: 244998144 02:33:32 INFO - PROCESS | 1742 | 02:33:34 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator) 02:33:35 INFO - PROCESS | 1742 | RSS: Main: 247619584 02:33:35 INFO - PROCESS | 1742 | 02:33:37 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:33:37 INFO - PROCESS | 1742 | RSS: Main: 248356864 02:33:37 INFO - PROCESS | 1742 | 02:33:40 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom) 02:33:41 INFO - PROCESS | 1742 | RSS: Main: 252260352 02:33:41 INFO - PROCESS | 1742 | 02:33:44 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate) 02:33:45 INFO - PROCESS | 1742 | RSS: Main: 251883520 02:33:45 INFO - PROCESS | 1742 | 02:33:48 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial) 02:33:48 INFO - PROCESS | 1742 | RSS: Main: 251813888 02:33:48 INFO - PROCESS | 1742 | 02:33:50 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:33:51 INFO - PROCESS | 1742 | RSS: Main: 251379712 02:33:51 INFO - PROCESS | 1742 | 02:33:53 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:33:53 INFO - PROCESS | 1742 | RSS: Main: 251412480 02:33:53 INFO - PROCESS | 1742 | 02:33:56 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:33:56 INFO - PROCESS | 1742 | RSS: Main: 251498496 02:33:56 INFO - PROCESS | 1742 | 02:33:59 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:34:00 INFO - PROCESS | 1742 | RSS: Main: 252104704 02:34:00 INFO - PROCESS | 1742 | 02:34:03 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:34:04 INFO - PROCESS | 1742 | RSS: Main: 251625472 02:34:04 INFO - PROCESS | 1742 | 02:34:05 INFO - PROCESS | 1742 | Cycle 1(1): loaded http://localhost:49254/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49254/tests/kraken/driver.html?testName=ai-astar) 02:34:06 INFO - PROCESS | 1742 | RSS: Main: 253612032 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | __start_tp_report 02:34:06 INFO - PROCESS | 1742 | _x_x_mozilla_page_load 02:34:06 INFO - PROCESS | 1742 | _x_x_mozilla_page_load_details 02:34:06 INFO - PROCESS | 1742 | |i|pagename|runs| 02:34:06 INFO - PROCESS | 1742 | |0;ai-astar;109;124;101;146;180;178;121;118;123;121 02:34:06 INFO - PROCESS | 1742 | |1;audio-beat-detection;172;143;110;148;109;116;114;114;132;113 02:34:06 INFO - PROCESS | 1742 | |2;audio-dft;195;199;224;284;201;274;198;198;196;198 02:34:06 INFO - PROCESS | 1742 | |3;audio-fft;85;62;61;101;59;86;56;51;66;53 02:34:06 INFO - PROCESS | 1742 | |4;audio-oscillator;88;70;86;72;70;71;82;70;72;84 02:34:06 INFO - PROCESS | 1742 | |5;imaging-gaussian-blur;125;90;127;125;91;90;93;90;91;92 02:34:06 INFO - PROCESS | 1742 | |6;imaging-darkroom;95;129;156;122;98;98;125;95;97;98 02:34:06 INFO - PROCESS | 1742 | |7;imaging-desaturate;98;122;91;93;92;87;87;116;121;90 02:34:06 INFO - PROCESS | 1742 | |8;json-parse-financial;66;66;65;66;71;69;69;71;70;69 02:34:06 INFO - PROCESS | 1742 | |9;json-stringify-tinderbox;58;64;49;62;75;70;59;59;60;70 02:34:06 INFO - PROCESS | 1742 | |10;stanford-crypto-aes;83;84;105;70;91;103;104;85;85;102 02:34:06 INFO - PROCESS | 1742 | |11;stanford-crypto-ccm;141;166;146;143;147;147;156;145;184;142 02:34:06 INFO - PROCESS | 1742 | |12;stanford-crypto-pbkdf2;148;227;175;188;214;192;161;154;168;217 02:34:06 INFO - PROCESS | 1742 | |13;stanford-crypto-sha256-iterative;66;67;68;65;68;61;66;68;68;67 02:34:06 INFO - PROCESS | 1742 | __end_tp_report 02:34:06 INFO - PROCESS | 1742 | __start_cc_report 02:34:06 INFO - PROCESS | 1742 | _x_x_mozilla_cycle_collect,1033 02:34:06 INFO - PROCESS | 1742 | __end_cc_report 02:34:06 INFO - PROCESS | 1742 | __startTimestamp1492853646087__endTimestamp 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | ------- Summary: start ------- 02:34:06 INFO - PROCESS | 1742 | Number of tests: 14 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#0] ai-astar Cycles:10 Average:132.10 Median:123.50 stddev:27.26 (22.1%) stddev-sans-first:27.60 02:34:06 INFO - PROCESS | 1742 | Values: 109.0 124.0 101.0 146.0 180.0 178.0 121.0 118.0 123.0 121.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#1] audio-beat-detection Cycles:10 Average:127.10 Median:124.00 stddev:21.12 (17.0%) stddev-sans-first:14.90 02:34:06 INFO - PROCESS | 1742 | Values: 172.0 143.0 110.0 148.0 109.0 116.0 114.0 114.0 132.0 113.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#2] audio-dft Cycles:10 Average:216.70 Median:200.00 stddev:33.95 (17.0%) stddev-sans-first:35.09 02:34:06 INFO - PROCESS | 1742 | Values: 195.0 199.0 224.0 284.0 201.0 274.0 198.0 198.0 196.0 198.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#3] audio-fft Cycles:10 Average:68.00 Median:61.50 stddev:16.77 (27.3%) stddev-sans-first:16.62 02:34:06 INFO - PROCESS | 1742 | Values: 85.0 62.0 61.0 101.0 59.0 86.0 56.0 51.0 66.0 53.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:77.00 stddev:7.50 (9.7%) stddev-sans-first:6.70 02:34:06 INFO - PROCESS | 1742 | Values: 88.0 70.0 86.0 72.0 70.0 71.0 82.0 70.0 72.0 84.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#5] imaging-gaussian-blur Cycles:10 Average:101.40 Median:90.50 stddev:16.78 (18.5%) stddev-sans-first:15.47 02:34:06 INFO - PROCESS | 1742 | Values: 125.0 90.0 127.0 125.0 91.0 90.0 93.0 90.0 91.0 92.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#6] imaging-darkroom Cycles:10 Average:111.30 Median:96.00 stddev:20.76 (21.6%) stddev-sans-first:21.17 02:34:06 INFO - PROCESS | 1742 | Values: 95.0 129.0 156.0 122.0 98.0 98.0 125.0 95.0 97.0 98.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#7] imaging-desaturate Cycles:10 Average:99.70 Median:90.50 stddev:14.21 (15.7%) stddev-sans-first:15.05 02:34:06 INFO - PROCESS | 1742 | Values: 98.0 122.0 91.0 93.0 92.0 87.0 87.0 116.0 121.0 90.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:69.00 stddev:2.25 (3.3%) stddev-sans-first:2.24 02:34:06 INFO - PROCESS | 1742 | Values: 66.0 66.0 65.0 66.0 71.0 69.0 69.0 71.0 70.0 69.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:7.49 (11.9%) stddev-sans-first:7.75 02:34:06 INFO - PROCESS | 1742 | Values: 58.0 64.0 49.0 62.0 75.0 70.0 59.0 59.0 60.0 70.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:83.50 stddev:11.81 (14.1%) stddev-sans-first:12.15 02:34:06 INFO - PROCESS | 1742 | Values: 83.0 84.0 105.0 70.0 91.0 103.0 104.0 85.0 85.0 102.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:147.00 stddev:13.63 (9.3%) stddev-sans-first:13.90 02:34:06 INFO - PROCESS | 1742 | Values: 141.0 166.0 146.0 143.0 147.0 147.0 156.0 145.0 184.0 142.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:190.00 stddev:27.85 (14.7%) stddev-sans-first:26.24 02:34:06 INFO - PROCESS | 1742 | Values: 148.0 227.0 175.0 188.0 214.0 192.0 161.0 154.0 168.0 217.0 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - PROCESS | 1742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.50 stddev:2.17 (3.2%) stddev-sans-first:2.30 02:34:06 INFO - PROCESS | 1742 | Values: 66.0 67.0 68.0 65.0 68.0 61.0 66.0 68.0 68.0 67.0 02:34:06 INFO - PROCESS | 1742 | -------- Summary: end -------- 02:34:06 INFO - PROCESS | 1742 | 02:34:06 INFO - TEST-INFO | 1742: exit 0 02:34:07 INFO - TEST-OK | kraken | took 61787ms 02:34:07 INFO - SUITE-END | took 1178s 02:34:07 INFO - Completed test suite (00:19:38) 02:34:07 INFO - javascript benchmark 02:34:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7580.42928804668, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.223295292654, 3402.2174899773067, 3467.2372078142807, 3468.2023134679025, 3471.2016236265645, 4777.737224452662, 5770.798022069229, 5772.3361813391475, 5862.2965244170655, 5866.64800111993, 5320.068987927107, 5371.060064488718, 5854.88290234195, 5892.263467066617, 5894.17481552583, 5256.5794736421185, 5335.866603334909, 5351.0, 5355.250264962909, 5393.6763794172275, 5407.270018547485, 5418.376886658046, 5418.756155972971, 5430.8913821723545, 5455.454454554552, 5202.635815492917, 5211.739413029352, 5322.627416080876, 5364.463553644624, 5369.9463005369835, 5332.280142180814, 5339.145736682112, 5391.865203369908, 5419.891602167974, 5456.809011684572, 5283.55089817366, 5335.759890804923, 5341.706206158659, 5343.198520221998, 5356.758945847446, 5510.972445137749, 5813.854653633693, 5925.0, 5995.070764031582, 6000.399960004008, 5520.834374968757, 5652.689102099424, 6130.734471166763, 6257.5619706620555, 6267.247930248353, 6335.493160547145, 6349.746010159588, 6365.0, 6366.045093236052, 6386.904196437057, 6067.878642427127, 6073.908891366677, 6081.9929423290205, 6099.146119543268, 6114.755409783648, 4587.357769912215, 6215.223097112861, 6273.96863015682, 6401.839954001187, 6421.678916054178, 6141.946315431794, 6177.320494745574, 6239.719212635442, 6253.7185826637915, 6256.874862502725, 5507.394186639466, 6291.056341548713, 6316.0, 6346.492280617539, 6361.363863613602, 6270.529710271657, 6301.117843501914, 6318.526110541636, 6332.810015699536, 6352.237731472161, 4994.675346102492, 6160.322364539897, 6217.098520714471, 6217.595856269328, 6255.593386429959, 4492.415985921874, 5091.719955402488, 5097.413797413345, 5100.387953445536, 5111.591072714174, 3350.2964377480516, 4340.65274778017, 4540.77296135192, 4555.521670224645, 4557.384753058294, 5681.659100453986, 5731.484166425042, 5887.35239123696, 5950.16697662319, 5989.700514974234, 6319.747210111544, 6364.64994425311, 6388.84027899297, 6403.519736019817, 6419.967900160563, 6000.219971403777, 6025.186599809057, 6025.548083893726, 6031.788887389008, 6053.636781793019, 5408.350997880201, 5467.835964921059, 5663.631863928831, 5665.065264231438, 5673.035583950656, 6322.841928951721, 6487.675616219264, 6518.087467754613, 6541.836454088686, 6559.6064236145985, 6303.9684801575695, 6348.492120630431, 6379.681015949277, 6391.776287829904, 6400.55196136266, 4867.561919427127, 5506.311711036121, 5580.135079062589, 6471.579347342407, 6521.478281737544, 6123.1606109212, 6179.536534760001, 6244.0, 6257.217847769029, 6265.060240963892, 6313.273973493107, 6326.304106548184, 6328.335524769833, 6331.366863313816, 6335.018072198816, 5021.271915572294, 5029.84910452687, 5418.07892658257, 6179.351168127282, 6254.906176407449, 6201.875962480815, 6275.152854364603, 6297.685115744286, 6304.05439184126, 6347.746090156526, 5794.985877471341, 5951.7024148791525, 6102.298235702951, 6107.816765497042, 6112.816615501542, 6213.65824879636, 6221.160143380585, 6272.937270627418, 6280.874382512416, 6292.590981586182, 6126.846828829315, 6129.570930034854, 6137.754489820357, 6154.446099851035, 6211.937880621136], "value": 5743.298884576304, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [797.660994077517, 799.5242830515841, 818.6193420059673, 937.3391758810039, 966.4056605187811, 37008.81495592522, 38954.61045389545, 38986.0, 39071.60928390715, 39182.0, 37516.624833751725, 37776.81111594445, 37911.431328530096, 38018.479260829605, 38159.42760858582, 7807.609619519029, 10391.584336626525, 10673.0, 10856.674299771019, 10872.0, 10581.894181058206, 10590.993855583745, 10646.946765266162, 10663.733406664818, 10671.19966002549, 10666.253362264644, 10676.839847402296, 10686.572537098507, 10691.411972341519, 10706.892931070668, 10630.62792802252, 10631.255812093157, 10648.57405703771, 10649.361038337725, 10671.626493072705, 10414.479276036167, 10415.635452759192, 10478.266521343508, 10503.0, 10504.684859454228, 32637.6736232637, 32877.50683739746, 32880.0, 32910.83544582286, 33106.6689333106, 3646.9452958205648, 3675.4119340905327, 3698.7225958052986, 3720.7395482316247, 3759.2669429461266, 24432.877835610885, 24484.14305499317, 24777.380565485826, 25877.094301699537, 25883.611745823826, 3046.923826904323, 3066.540018997146, 3157.605299337583, 3228.790128641631, 3263.4288999425244, 340264.0, 363048.0, 364033.0, 364317.0, 365347.9999999973, 35726.821365893, 35853.82073089618, 51534.74232628813, 52642.73678631582, 52742.0, 43253.78373108177, 53020.20469692958, 55697.0, 55825.0, 55909.0, 12292.201006934522, 12440.75118497643, 15093.622659433602, 15120.54638360851, 15188.620284492976, 11443.542258309575, 12700.237985720887, 12903.935480322538, 12994.610161695164, 13024.218546887218, 11002.0, 13148.07963442569, 13331.400231972226, 13334.133281336683, 13361.53234636783, 4572.702774319659, 5400.83797486076, 5939.762409503572, 6002.54980876436, 6015.368386319461, 1058.222206678091, 1136.8976792088586, 1138.9031932285682, 1144.753877916252, 1175.976480470403, 21902.890485547472, 26191.476170476864, 26228.73771262301, 26283.211503654922, 26512.337191570365, 1001.3991605037065, 1047.7275908263907, 1080.56777289085, 1101.3557069114497, 1197.3294954825326, 11017.889821101846, 12568.120231583702, 12986.0, 13044.739105217843, 13250.204987700768, 495406.0, 498228.0, 498566.0, 500505.0, 500615.0, 43915.56084439179, 44298.0, 44389.33415998763, 44400.3339949901, 44428.3335749964, 1381.785823197406, 1415.1084816565703, 1415.3418660323, 1420.4176287721984, 1424.2949739878827, 24192.206316859996, 24604.138855139983, 24935.251942441755, 24962.875185623954, 25036.62445063362, 37416.62583374132, 37756.43365349467, 37781.4332785014, 37844.43233351557, 37918.0, 481769.0, 485779.0, 487355.0, 487704.0, 489865.0, 28828.567571486863, 29797.5530367049, 30340.84829575838, 31344.05967820969, 31415.685843141277, 37645.81177094097, 37795.0, 38518.0, 38527.6147238524, 38843.80578097091, 27101.1869643911, 27125.86437067802, 27160.592591111545, 27487.587686184324, 28227.0, 22802.315930522112, 27442.313942151606, 27546.0, 27733.44533109367, 28267.71732282733, 31709.682903170673, 31918.52122218122, 32183.873564425132, 32238.838805805823, 32320.35359292754, 27257.0, 27659.170224893285, 27776.0, 28515.28711782222, 28775.71224287814, 19939.401817945483, 21258.7874121263, 21433.78566214318, 22404.759524047466, 22584.5483090334], "value": 18008.187542771928, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.2433158015549, 408.20807633191606, 410.40696193999685, 419.89292730353753, 451.494326354483, 19590.804091959075, 19936.90031549842, 20272.797272027274, 20513.692294615576, 20754.792452075475, 17855.73216401755, 21246.57506849866, 21542.0, 21558.568828623418, 21877.453063673416, 5778.711064446782, 6360.713767880433, 6539.901901471481, 6554.311797261291, 6575.210974683021, 5231.555317797992, 6288.811335659914, 6502.642354670491, 6519.739210431577, 6610.768623098193, 5663.405342439047, 6513.739450422001, 6523.151990241262, 6528.281888992207, 6544.836379090537, 6298.748050077991, 6437.776822403728, 6502.577332473374, 6524.086627872054, 6526.445252153572, 5558.777069044803, 5890.823275301705, 6303.212098487689, 6334.208223972004, 6391.8402039948905, 25230.747692523022, 25386.11148609808, 25583.360415989562, 25617.615735763793, 26113.43319400842, 2864.1264414353614, 2864.799464037518, 2870.7846911481724, 2877.4820532304175, 2889.8266104033823, 15844.841551584566, 16213.594660133473, 16684.416045438353, 16704.916475417667, 17205.91397043007, 1806.3948577226645, 2013.234970711135, 2098.7586427560873, 2113.4505028692497, 2123.0, 126408.10387844189, 134840.65159348378, 135254.6474535252, 137097.0, 137572.31213843866, 17579.033153176697, 20354.592908141753, 21988.450288742908, 22030.779692203192, 22309.442263943532, 34885.30229395398, 35372.0, 35800.462993055124, 35808.28383432317, 35836.46245306323, 9906.455144966953, 10117.62235874569, 10875.891241087646, 10924.344539327665, 11247.887521124683, 8916.777080573038, 8917.910820891837, 8951.910480895238, 9135.54322283883, 9146.359754817098, 10086.546105425274, 10421.68734937953, 10512.947435262928, 10514.894851051544, 10517.9474102629, 3755.7183211259266, 4334.393184954109, 4437.356583295404, 4464.888377790517, 4488.775561221991, 1095.9342439453499, 1097.8353247013013, 1101.1906248907046, 1101.515333253366, 1148.9942550287362, 21253.36239912805, 21455.0, 22242.443938901655, 22324.330270091923, 22489.887550562144, 900.7297810656777, 947.7962238118838, 979.4270351844109, 982.2878413150409, 1008.7730260691286, 8279.793005174919, 9998.700038998697, 10592.470376481146, 10708.0, 10757.677269681923, 779646.1017694989, 784986.0, 786322.0683896658, 790714.0, 796856.0, 116618.0, 133866.99999999805, 169050.0, 169258.15370923313, 170421.29578704303, 1018.994905025485, 1240.5430265204543, 1276.9936150319188, 1282.6344491819787, 1282.7562763074986, 15822.68354632924, 18751.718724218874, 19360.903195483934, 19466.707999379738, 20153.193872244945, 49555.18057372226, 62521.0, 64220.43118275217, 66185.33814661985, 67591.32408676048, 69918.0, 95926.0, 96037.5198124005, 98026.0, 98106.50946745506, 10804.189685773441, 10978.011978921935, 11168.497417616065, 11279.7744045117, 11504.712382190512, 12024.33866137371, 12073.85298396651, 12294.815577766161, 12430.81353779676, 12481.313527756061, 8627.482351058956, 10581.576736930745, 10697.572097116028, 10713.35719856811, 10979.231453798477, 8823.955880220557, 9128.49793261377, 9823.21414286841, 9943.950280248553, 9974.60101595957, 10326.741831453974, 10968.067714244378, 11020.44897755093, 11112.716690999428, 11242.426060351721, 10907.618233361794, 11049.33703977764, 11150.665480035612, 11208.83186752183, 11232.719182020515, 10672.8932710675, 10688.305260158066, 10865.945670271598, 10938.343699377745, 10971.341719496855], "value": 11418.009471084386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.7019910918264, 1485.0718301061836, 1523.1394262241834, 1552.1451865021297, 1556.1907807939872, 10485.633002844901, 10881.23831331807, 11150.275232109918, 11161.162912781543, 11231.719207019829, 10954.342739435639, 11134.554617815298, 11151.776964460705, 11163.888361116386, 11172.720681982955, 1996.331229038274, 2260.683504309394, 2342.1919437793936, 2355.5171189906114, 2356.045801450415, 2288.1762565476415, 2345.4605440748637, 2362.303120579433, 2364.1016413762745, 2366.8993917828207, 2316.9110518056573, 2341.1454818991033, 2344.6248600223967, 2366.325597204797, 2369.8104151667912, 2290.095412312144, 2304.066852924568, 2344.636581329888, 2352.5882970480097, 2352.8588284702887, 2336.9649455258186, 2340.2979106268053, 2343.906243750248, 2344.308429013445, 2351.130081869702, 11068.837772033901, 11120.110391168768, 11131.499082541304, 11202.103831693445, 11240.887591124148, 5502.779888804443, 5668.234788303567, 5671.0, 5676.3188417389765, 5683.005474042067, 3314.5193946877807, 3428.6057103432927, 3561.9109522261892, 3578.785272883635, 3606.0, 1101.372217835834, 1119.3060302612432, 1171.7363593191549, 1179.4633441784054, 1186.0748616079472, 13421.463141474329, 13601.387937542835, 14525.999999999894, 15217.391304347813, 15252.08487490743, 4499.68502204849, 4612.285095810154, 4619.376384188159, 4625.861224163281, 4626.421697287839, 10729.0, 11585.0, 11679.416029198506, 11732.0, 11760.000399966098, 4711.575958163782, 5545.861353466115, 5562.860928476821, 5609.186667933209, 5614.887702245932, 5002.824901128443, 5117.41149767774, 5163.741812909339, 5174.689518628894, 5176.068307704574, 3681.687056600219, 3814.5422549293708, 3819.1788765415567, 3848.191879705293, 3912.061105334756, 907.6278725722555, 915.2815040193416, 970.3498655900563, 970.9174720148867, 976.3165783951263, 917.936478795661, 928.8374534456443, 935.7286386947845, 935.8830146231721, 977.3988996766938, 5458.426865179178, 5544.528715059265, 5609.326880774252, 6279.560430769802, 6301.747930082837, 849.6771226933726, 984.17329443268, 1004.3572113846998, 1010.7927874785652, 1016.5527168046036, 4308.418363520885, 4325.978370108192, 4393.868183954486, 4415.690901636919, 4442.244818380883, 13017.869821301856, 13236.735265294641, 13248.536301229411, 13540.390682419315, 13585.320733963263, 13329.133606315558, 13331.933340333237, 13474.393652285671, 13495.797563036562, 13519.932400337935, 10103.696889093339, 10106.292559520763, 10117.848232276374, 10223.335483193569, 10234.795304093876, 6456.999165129412, 6494.155759751202, 6519.967400162969, 6587.769428069994, 6778.559393639398, 11760.941195293968, 11973.580924667594, 12116.515339386326, 12164.939175304067, 12237.326947018, 12218.26690398579, 12267.815982760445, 12324.0, 12398.5660501882, 12443.191192572454, 8313.043999940084, 8495.277901378206, 8495.95752021236, 8499.702510412106, 8505.74482765518, 9876.85184722244, 10120.0, 10206.948965255424, 10212.336198147097, 10219.948900255451, 8587.527685977331, 8730.69442569507, 8760.868586971328, 8766.254868336262, 8904.332175087024, 8100.797480063045, 8536.274416674403, 8570.314374849871, 8636.481811091355, 8755.343349248958, 7663.578503182379, 7899.644515996909, 8107.4324797263625, 8115.59422028908, 8158.959205203936, 4758.262469317261, 5836.737346819318, 5900.203472531153, 5981.431763982415, 6132.172156758959, 5938.10928360732, 5983.401659833982, 6007.429294217042, 6062.818115456544, 6149.815505534841], "value": 5027.97307552427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.38081642024974, 371.8902923637527, 373.3913720635364, 384.7768294389253, 385.8726620215329, 4574.879154607126, 4705.64707646927, 4708.552687494687, 4981.651284410093, 5211.296474975878, 4361.454818147731, 4520.095980803844, 4764.5235476452335, 4856.878578035551, 5233.65981211221, 4170.955202702029, 4344.934825977605, 4365.607095361417, 4371.3661519079715, 4389.429374181368, 3707.516993202727, 3852.9807350963206, 4298.905796631002, 4317.12205190743, 4385.26781921141, 3563.465480177968, 3651.3427583035045, 3878.1772566893515, 4388.736675799462, 4396.648268138557, 3995.94006089906, 4047.494063242095, 4244.257254980397, 4295.355696645513, 4299.892502687427, 3299.3566254580364, 3484.9545136459215, 3564.269324788438, 3983.601639836022, 3987.1029018470904, 4467.917137175338, 5078.886102257184, 5091.9236211456855, 5107.106256405114, 5121.385433747974, 4180.91638167238, 4736.905261894777, 4994.07609592223, 4996.400431948153, 5079.949200507985, 4628.0, 5554.583406244547, 5580.414056524047, 5598.776048958037, 5618.213450116987, 3821.8853434396733, 4383.956160438419, 4439.800208990603, 4517.932231016504, 4557.9772101139615, 5155.226715992593, 5309.65487243328, 5596.4123767004285, 5970.522358211333, 6030.728617212236, 4219.07180420307, 4310.54739252374, 4515.638748900145, 4538.364628951949, 4542.795574199103, 4188.01069491995, 4623.583877451046, 4651.023285110165, 4672.813087476531, 4734.4081989751285, 4251.149394000871, 4481.865544033684, 4991.6306493117, 5026.346574945307, 5043.495650435, 4812.759362031885, 5393.5954803389905, 5509.449055094458, 5516.668999859941, 5545.223668686387, 1269.9428525716364, 1387.1273780396384, 1546.4200924653255, 1553.829078801331, 1562.742147545665, 958.9355815045294, 1059.5814653212092, 1126.7859106769688, 1182.5565412970136, 1214.8094867030359, 4604.815807367668, 4676.298555216745, 4987.426445958761, 5252.369715634149, 5253.290805741252, 5281.287026251407, 5790.971045144747, 5881.764729410861, 5899.262592175978, 5930.525557955353, 4716.764161791897, 4798.352222449925, 4842.878928026827, 5208.583313334924, 5235.947640523622, 2564.743525647458, 3001.904304928685, 3029.363833594926, 3035.5598438226775, 3069.723724864773, 5790.739416726257, 6007.068904319837, 6023.427774361428, 6043.2748070231855, 6074.301455332693, 4393.846215382447, 5428.511433970962, 5628.521575666033, 5848.385919478394, 5874.50066744323, 4542.5911667949385, 5698.344690360579, 5992.0, 6044.607100538451, 6069.332373438918, 5076.82698313554, 5104.438511763628, 5109.948900510947, 5354.518093371616, 5414.404415514212, 5176.2494438306985, 5277.604179686616, 5469.726513674221, 5659.320881494247, 5805.448482394166, 5761.193432919311, 5897.967855625334, 6101.60339577926, 6107.755689772359, 6116.571839971337, 4154.459920210354, 4178.5318185906235, 4356.128774245227, 4671.85984420468, 4682.765861706969, 5399.2980912481125, 5479.178223214968, 5494.80768173112, 5666.336540972625, 5720.3421606515785, 5016.6237532184505, 5081.568066714222, 5442.591805614516, 5521.944780552143, 5644.266245388073, 5240.34495688039, 5471.890562188813, 5491.615586908878, 5525.336959564878, 5587.636803607753, 4448.310511870665, 4574.473935497461, 4614.861554153381, 5388.38033626138, 5441.673499590037, 5256.0, 5523.309586301712, 5531.976584331912, 5578.804741834017, 5610.4670056344585, 4624.768761561976, 5065.366829146356, 5328.333958255218, 5364.678119312854, 5472.452754724496], "value": 4219.167385700264, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.165105953683, 732.3079689693235, 745.7091734223653, 825.0841565861895, 834.8079941613427, 15311.54065378039, 15809.525714228563, 15941.0, 16324.673506529862, 16385.918070409647, 13003.349832508384, 15549.300281487333, 15695.529134125965, 15742.134182619951, 15748.370065197407, 765.9923400766005, 893.0578239956843, 942.4439580647417, 946.1768261612373, 950.6340059077236, 912.7261821453537, 925.2366797392144, 926.3098991251493, 927.5686805635378, 928.7724507495673, 1004.5128112865291, 1005.1857995024054, 1008.1682611845221, 1008.8587597736323, 1011.1405305490312, 920.2408013388947, 988.1452543549777, 989.3025417080977, 1001.444198469849, 1005.4118340770658, 735.51456039014, 745.4744405194325, 746.4177941205868, 746.436440487434, 747.7943565519471, 16621.750673739905, 16671.0, 16866.241019154168, 16925.23836427364, 17257.223424945907, 13871.729696527787, 13895.722085558233, 13911.373988170557, 14078.929605351908, 14139.929300353535, 11790.764184716343, 11818.231814932002, 11892.286462812259, 11893.286402815773, 11919.821202681967, 3550.3254381667402, 3607.963920360789, 3611.3138503684218, 3648.616895226016, 3759.7368184227116, 93089.0, 114434.42782786116, 115809.5257142287, 116503.0, 117482.0, 10836.620718274822, 10906.890931090746, 10916.617918372978, 10981.835272470918, 11207.887921120848, 22456.775432245795, 22717.432064198198, 22740.0, 22767.658485122738, 22797.772022279896, 7638.388928885676, 7691.077070751434, 7719.575423351657, 7721.266479684421, 7959.522428654299, 4137.420761093449, 4175.185838761412, 4227.8097485613225, 4238.279492486285, 4283.271843786501, 3389.5593572835373, 3454.8790792322147, 3518.806465644363, 3532.788032718045, 3552.2540266544315, 627.137685682187, 628.8176428835586, 630.8422894276431, 635.5011316116919, 643.5848877474003, 666.3802663522873, 666.4335314982324, 689.4587748617312, 691.4675699711195, 697.616311028932, 9822.263330250116, 10356.067953884187, 10709.357438553712, 10883.891161088288, 11372.886271137348, 571.6312978129083, 585.9209006784115, 591.5622439394817, 595.592019466658, 710.4281053751677, 4846.903061938741, 5027.547520723152, 5059.924101138486, 5066.36670416198, 5078.4413714491375, 87777.0, 87921.0, 88647.0, 90130.0, 91288.08711912928, 79666.0, 82431.58784206041, 83004.0, 83939.58030209932, 84710.5764471186, 60032.09951850726, 65002.374940625916, 66794.0, 75536.24463755293, 75908.99151144085, 8388.0, 10731.443940628722, 10884.292520986428, 11319.830202546804, 11386.658400248005, 47382.28926566035, 47424.81437964078, 48144.55566333015, 49459.258111127645, 49967.0, 68182.0, 70301.64849175721, 72625.0, 72690.27309726835, 72957.0, 12356.443960022001, 12396.256224626191, 12564.811527827273, 12774.744505110031, 12929.54746583865, 14434.0, 14509.0, 14515.419383224553, 14821.40714371413, 14934.0, 13419.731605368033, 13605.183688978694, 13896.51362202318, 14379.065360751518, 14416.927915360357, 13229.735405292033, 13671.17972921628, 13678.863211368158, 13687.589372318846, 13703.588892333246, 9848.901510985084, 11996.400179990791, 12069.698257543281, 12373.75252494927, 12700.87299126997, 9776.853347199656, 10194.490275486345, 10203.0, 10344.793104138025, 10354.689359319233, 10090.0, 11274.0, 11331.47025151594, 11461.369624670722, 11555.768884622092], "value": 7573.220811404886, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 124.0, 101.0, 146.0, 180.0, 178.0, 121.0, 118.0, 123.0, 121.0], "value": 132.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 143.0, 110.0, 148.0, 109.0, 116.0, 114.0, 114.0, 132.0, 113.0], "value": 127.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 224.0, 284.0, 201.0, 274.0, 198.0, 198.0, 196.0, 198.0], "value": 216.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 61.0, 101.0, 59.0, 86.0, 56.0, 51.0, 66.0, 53.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 86.0, 72.0, 70.0, 71.0, 82.0, 70.0, 72.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 90.0, 127.0, 125.0, 91.0, 90.0, 93.0, 90.0, 91.0, 92.0], "value": 101.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 129.0, 156.0, 122.0, 98.0, 98.0, 125.0, 95.0, 97.0, 98.0], "value": 111.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 122.0, 91.0, 93.0, 92.0, 87.0, 87.0, 116.0, 121.0, 90.0], "value": 99.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 71.0, 69.0, 69.0, 71.0, 70.0, 69.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 49.0, 62.0, 75.0, 70.0, 59.0, 59.0, 60.0, 70.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 105.0, 70.0, 91.0, 103.0, 104.0, 85.0, 85.0, 102.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 166.0, 146.0, 143.0, 147.0, 147.0, 156.0, 145.0, 184.0, 142.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 227.0, 175.0, 188.0, 214.0, 192.0, 161.0, 154.0, 168.0, 217.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 65.0, 68.0, 61.0, 66.0, 68.0, 68.0, 67.0], "value": 66.4, "unit": "score"}]}]} 02:34:07 INFO - Return code: 0 02:34:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:34:07 ERROR - # TBPL SUCCESS # 02:34:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:34:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:34:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:34:07 INFO - 2017-04-22 02:34:07.412 screenresolution[1747:18833] starting screenresolution argv=screenresolution get 02:34:07 INFO - 2017-04-22 02:34:07.415 screenresolution[1747:18833] Display 0: 1600x1200x32@60 02:34:07 INFO - 2017-04-22 02:34:07.421 screenresolution[1748:18836] starting screenresolution argv=screenresolution list 02:34:07 INFO - Available Modes on Display 0 02:34:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:34:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:34:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:34:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:34:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:34:07 INFO - Intel Iris: 02:34:07 INFO - Chipset Model: Intel Iris 02:34:07 INFO - Type: GPU 02:34:07 INFO - Bus: Built-In 02:34:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:34:07 INFO - Vendor: Intel (0x8086) 02:34:07 INFO - Device ID: 0x0a2e 02:34:07 INFO - Revision ID: 0x0009 02:34:07 INFO - Displays: 02:34:07 INFO - Display: 02:34:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:34:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:34:07 INFO - Display Serial Number: 1600x1200 60 02:34:07 INFO - Main Display: Yes 02:34:07 INFO - Mirror: Off 02:34:07 INFO - Online: Yes 02:34:07 INFO - Rotation: Supported 02:34:07 INFO - Return code: 0 02:34:07 INFO - Running post-action listener: _resource_record_post_action 02:34:07 INFO - [mozharness: 2017-04-22 09:34:07.665575Z] Finished run-tests step (success) 02:34:07 INFO - Running post-run listener: _resource_record_post_run 02:34:07 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 33901568; Write bytes: 514601472; Read time: 316; Write time: 1739 02:34:07 INFO - TinderboxPrint: CPU usage
54.5% 02:34:07 INFO - TinderboxPrint: I/O read bytes / time
33,901,568 / 316 02:34:07 INFO - TinderboxPrint: I/O write bytes / time
514,601,472 / 1,739 02:34:07 INFO - TinderboxPrint: CPU idle
2,183.0 (45.5%) 02:34:07 INFO - TinderboxPrint: CPU system
962.0 (20.1%) 02:34:07 INFO - TinderboxPrint: CPU user
1,652.6 (34.4%) 02:34:07 INFO - TinderboxPrint: Swap in / out
239,235,072 / 0 02:34:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203862528; Write bytes: 199294976; Read time: 15703; Write time: 375 02:34:07 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 31988736; Write bytes: 304517632; Read time: 287; Write time: 1329 02:34:08 INFO - Running post-run listener: _upload_blobber_files 02:34:08 INFO - Blob upload gear active. 02:34:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:34:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:34:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:34:08 INFO - (blobuploader) - INFO - Open directory for files ... 02:34:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:34:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:34:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:09 INFO - (blobuploader) - INFO - Done attempting. 02:34:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:34:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:34:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:10 INFO - (blobuploader) - INFO - Done attempting. 02:34:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:34:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:34:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:11 INFO - (blobuploader) - INFO - Done attempting. 02:34:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:34:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:34:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:13 INFO - (blobuploader) - INFO - Done attempting. 02:34:13 INFO - (blobuploader) - INFO - Iteration through files over. 02:34:13 INFO - Return code: 0 02:34:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:34:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:34:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7ebb7d3dfe3be0abf77e8ad44f155ce54e79c9bc151e3b821babb897b54fb7f0645d5c774f5d015b94916eaa7815cf32d231a58a67b2eaced59fa74e9f2445c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01a5e31d0f5e547cc5e9027d8d9a43a1a1f2a4fc2f0737e1377a46dd3df1ecdd58625ef6ce7127ecbe2b07c2afb599d98da66b92c277fbb0c591edeec6f8ad81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2c5db0d96fa7492c6cb569724338c569f4067f56f988b6518731b660adc081193b8533e6843b565fdc51bbe9d1841d9c15dec9cc41064ed91536183363a86e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e8adb606b8cb54112d171362a2d3e01090f2788ed5454b5d9b79d234df9873b7a1a0a98b1fe5d06e576c95b94d41259461095c63a26979ae6b07e5d2a030ee9"} 02:34:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:34:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:34:13 INFO - Contents: 02:34:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c7ebb7d3dfe3be0abf77e8ad44f155ce54e79c9bc151e3b821babb897b54fb7f0645d5c774f5d015b94916eaa7815cf32d231a58a67b2eaced59fa74e9f2445c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01a5e31d0f5e547cc5e9027d8d9a43a1a1f2a4fc2f0737e1377a46dd3df1ecdd58625ef6ce7127ecbe2b07c2afb599d98da66b92c277fbb0c591edeec6f8ad81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2c5db0d96fa7492c6cb569724338c569f4067f56f988b6518731b660adc081193b8533e6843b565fdc51bbe9d1841d9c15dec9cc41064ed91536183363a86e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e8adb606b8cb54112d171362a2d3e01090f2788ed5454b5d9b79d234df9873b7a1a0a98b1fe5d06e576c95b94d41259461095c63a26979ae6b07e5d2a030ee9"} 02:34:13 INFO - Running post-run listener: copy_logs_to_upload_dir 02:34:13 INFO - Copying logs to upload dir... 02:34:13 INFO - mkdir: /builds/slave/test/build/upload/logs 02:34:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.802799 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-22 02:34:13.840126) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-22 02:34:13.844202) ========= 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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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/c7ebb7d3dfe3be0abf77e8ad44f155ce54e79c9bc151e3b821babb897b54fb7f0645d5c774f5d015b94916eaa7815cf32d231a58a67b2eaced59fa74e9f2445c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01a5e31d0f5e547cc5e9027d8d9a43a1a1f2a4fc2f0737e1377a46dd3df1ecdd58625ef6ce7127ecbe2b07c2afb599d98da66b92c277fbb0c591edeec6f8ad81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2c5db0d96fa7492c6cb569724338c569f4067f56f988b6518731b660adc081193b8533e6843b565fdc51bbe9d1841d9c15dec9cc41064ed91536183363a86e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e8adb606b8cb54112d171362a2d3e01090f2788ed5454b5d9b79d234df9873b7a1a0a98b1fe5d06e576c95b94d41259461095c63a26979ae6b07e5d2a030ee9"} build_url:https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010039 build_url: 'https://queue.taskcluster.net/v1/task/K5cFptS_RcWE8Mi64O-jJw/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/c7ebb7d3dfe3be0abf77e8ad44f155ce54e79c9bc151e3b821babb897b54fb7f0645d5c774f5d015b94916eaa7815cf32d231a58a67b2eaced59fa74e9f2445c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01a5e31d0f5e547cc5e9027d8d9a43a1a1f2a4fc2f0737e1377a46dd3df1ecdd58625ef6ce7127ecbe2b07c2afb599d98da66b92c277fbb0c591edeec6f8ad81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2c5db0d96fa7492c6cb569724338c569f4067f56f988b6518731b660adc081193b8533e6843b565fdc51bbe9d1841d9c15dec9cc41064ed91536183363a86e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e8adb606b8cb54112d171362a2d3e01090f2788ed5454b5d9b79d234df9873b7a1a0a98b1fe5d06e576c95b94d41259461095c63a26979ae6b07e5d2a030ee9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-22 02:34:13.877592) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:34:13.878116) ========= 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.vfZ1HFefWj/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.bgvOQO4DYj/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.004670 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-22 02:34:13.929805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-22 02:34:13.930138) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-22 02:34:13.930465) ========= ========= Total master_lag: 0.18 =========