builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0146 starttime: 1491556276.75 results: success (0) buildid: 20170407004017 builduid: 8579217f9ff94e9f8ccc0394919ba48e revision: 92ba21762445f89ae0691c4eab0746ca1cb819c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:16.753660) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:16.754079) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:16.754360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:17.294648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:17.295054) ========= 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.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-07 02:11:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.3M=0.001s 2017-04-07 02:11:17 (16.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179946 ========= master_lag: 0.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:17.929023) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:17.929399) ========= 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.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.029125 ========= master_lag: 0.27 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:18.224199) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 02:11:18.224525) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 92ba21762445f89ae0691c4eab0746ca1cb819c2 --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 92ba21762445f89ae0691c4eab0746ca1cb819c2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-07 02:11:18,560 truncating revision to first 12 chars 2017-04-07 02:11:18,560 Setting DEBUG logging. 2017-04-07 02:11:18,560 attempt 1/10 2017-04-07 02:11:18,561 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/92ba21762445?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 02:11:19,872 unpacking tar archive at: mozilla-aurora-92ba21762445/testing/mozharness/ program finished with exit code 0 elapsedTime=1.599998 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-07 02:11:19.953938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:19.954634) ========= script_repo_revision: 92ba21762445f89ae0691c4eab0746ca1cb819c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:19.955310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:19.955998) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:20.351964) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-04-07 02:11:20.352409) ========= /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.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:11:20 INFO - MultiFileLogger online at 20170407 02:11:20 in /builds/slave/test 02:11:20 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:11:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:11:20 INFO - {'append_to_log': False, 02:11:20 INFO - 'base_work_dir': '/builds/slave/test', 02:11:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:11:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:11:20 INFO - 'branch': 'Mozilla-Aurora', 02:11:20 INFO - 'buildbot_json_path': 'buildprops.json', 02:11:20 INFO - 'config_files': ('talos/mac_config.py',), 02:11:20 INFO - 'default_actions': ('clobber', 02:11:20 INFO - 'read-buildbot-config', 02:11:20 INFO - 'download-and-extract', 02:11:20 INFO - 'populate-webroot', 02:11:20 INFO - 'create-virtualenv', 02:11:20 INFO - 'install', 02:11:20 INFO - 'run-tests'), 02:11:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:11:20 INFO - 'download_minidump_stackwalk': True, 02:11:20 INFO - 'download_symbols': 'ondemand', 02:11:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:11:20 INFO - 'tooltool.py': '/tools/tooltool.py', 02:11:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:11:20 INFO - '/tools/misc-python/virtualenv.py')}, 02:11:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:11:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:11:20 INFO - 'gecko_profile': False, 02:11:20 INFO - 'gecko_profile_interval': 0, 02:11:20 INFO - 'installer_path': 'installer.exe', 02:11:20 INFO - 'log_level': 'info', 02:11:20 INFO - 'log_name': 'talos', 02:11:20 INFO - 'log_to_console': True, 02:11:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:11:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:11:20 INFO - 'opt_config_files': (), 02:11:20 INFO - 'pip_index': False, 02:11:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:11:20 INFO - 'cmd': ('bash', 02:11:20 INFO - '-c', 02:11:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:11:20 INFO - 'enabled': True, 02:11:20 INFO - 'halt_on_failure': False, 02:11:20 INFO - 'name': 'check_screen_resolution'},), 02:11:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:11:20 INFO - 'cmd': ('bash', 02:11:20 INFO - '-c', 02:11:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:11:20 INFO - 'enabled': True, 02:11:20 INFO - 'halt_on_failure': False, 02:11:20 INFO - 'name': 'check_screen_resolution'},), 02:11:20 INFO - 'run_cmd_checks_enabled': True, 02:11:20 INFO - 'suite': 'dromaeojs-e10s', 02:11:20 INFO - 'system_bits': '32', 02:11:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:11:20 INFO - 'title': 't-yosemite-r7-0146', 02:11:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:11:20 INFO - 'use_talos_json': True, 02:11:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:11:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:11:20 INFO - 'work_dir': 'build'} 02:11:20 INFO - [mozharness: 2017-04-07 09:11:20.808963Z] Running clobber step. 02:11:20 INFO - Running pre-action listener: _resource_record_pre_action 02:11:20 INFO - Running main action method: clobber 02:11:20 INFO - rmtree: /builds/slave/test/build 02:11:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:11:23 INFO - Running post-action listener: _resource_record_post_action 02:11:23 INFO - [mozharness: 2017-04-07 09:11:23.396382Z] Finished clobber step (success) 02:11:23 INFO - [mozharness: 2017-04-07 09:11:23.396670Z] Running read-buildbot-config step. 02:11:23 INFO - Running pre-action listener: _resource_record_pre_action 02:11:23 INFO - Running main action method: read_buildbot_config 02:11:23 INFO - Using buildbot properties: 02:11:23 INFO - { 02:11:23 INFO - "project": "", 02:11:23 INFO - "product": "firefox", 02:11:23 INFO - "script_repo_revision": "production", 02:11:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:11:23 INFO - "repository": "", 02:11:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:11:23 INFO - "buildid": "20170407004017", 02:11:23 INFO - "stage_platform": "macosx64", 02:11:23 INFO - "basedir": "/builds/slave/test", 02:11:23 INFO - "nightly_build": "True", 02:11:23 INFO - "buildnumber": 15, 02:11:23 INFO - "slavename": "t-yosemite-r7-0146", 02:11:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:11:23 INFO - "platform": "yosemite_r7", 02:11:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:11:23 INFO - "branch": "mozilla-aurora", 02:11:23 INFO - "repo_path": "releases/mozilla-aurora", 02:11:23 INFO - "revision": "92ba21762445f89ae0691c4eab0746ca1cb819c2", 02:11:23 INFO - "pgo_build": "False", 02:11:23 INFO - "builduid": "8579217f9ff94e9f8ccc0394919ba48e", 02:11:23 INFO - "slavebuilddir": "test" 02:11:23 INFO - } 02:11:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:11:23 INFO - Running post-action listener: _resource_record_post_action 02:11:23 INFO - [mozharness: 2017-04-07 09:11:23.402541Z] Finished read-buildbot-config step (success) 02:11:23 INFO - [mozharness: 2017-04-07 09:11:23.402763Z] Running download-and-extract step. 02:11:23 INFO - Running pre-action listener: _resource_record_pre_action 02:11:23 INFO - Running main action method: download_and_extract 02:11:23 INFO - mkdir: /builds/slave/test/build/tests 02:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:11:23 INFO - trying https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:11:23 INFO - Downloading https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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:11:26 INFO - Downloaded 1199 bytes. 02:11:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:11:26 INFO - Using the following test package requirements: 02:11:26 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:11:26 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:11:26 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:11:26 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'jsshell-mac.zip'], 02:11:26 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:11:26 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:11:26 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:11:26 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:11:26 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:11:26 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:26 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:11:26 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:11:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:11:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:11:26 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:11:28 INFO - Expected file size: 51790493 02:11:28 INFO - Obtained file size: 51790493 02:11:44 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:11:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:11:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:11:44 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:11:47 INFO - Expected file size: 51790493 02:11:47 INFO - Obtained file size: 51790493 02:12:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:12:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:12:02 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:12:03 INFO - Expected file size: 14404424 02:12:03 INFO - Obtained file size: 14404424 02:12:03 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:03 INFO - trying https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:03 INFO - Downloading https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:12:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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:12:06 INFO - Downloaded 74700464 bytes. 02:12:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:06 INFO - mkdir: /builds/slave/test/properties 02:12:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:12:06 INFO - Writing to file /builds/slave/test/properties/build_url 02:12:06 INFO - Contents: 02:12:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:07 INFO - Running post-action listener: _resource_record_post_action 02:12:07 INFO - Running post-action listener: set_extra_try_arguments 02:12:07 INFO - [mozharness: 2017-04-07 09:12:07.088061Z] Finished download-and-extract step (success) 02:12:07 INFO - [mozharness: 2017-04-07 09:12:07.088222Z] Running populate-webroot step. 02:12:07 INFO - Running pre-action listener: _resource_record_pre_action 02:12:07 INFO - Running main action method: populate_webroot 02:12:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:12:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:12:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:12:07 INFO - '--tppagecycles', 02:12:07 INFO - '7'], 02:12:07 INFO - 'tests': ['tsvgm']}}, 02:12:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:12:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:12:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:12:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:12:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['damp', 'tps']}, 02:12:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'tests': ['damp', 'tps']}, 02:12:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['dromaeo_dom']}, 02:12:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:12:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['basic_compositor_video']}, 02:12:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:12:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'other-e10s': {'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['a11yr', 02:12:07 INFO - 'ts_paint', 02:12:07 INFO - 'tpaint', 02:12:07 INFO - 'sessionrestore', 02:12:07 INFO - 'sessionrestore_no_auto_restore', 02:12:07 INFO - 'tabpaint']}, 02:12:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['tsvgx', 02:12:07 INFO - 'tsvgr_opacity', 02:12:07 INFO - 'tart', 02:12:07 INFO - 'tscrollx', 02:12:07 INFO - 'cart', 02:12:07 INFO - 'tsvg_static']}, 02:12:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:12:07 INFO - 'tsvgr_opacity', 02:12:07 INFO - 'tart', 02:12:07 INFO - 'tscrollx', 02:12:07 INFO - 'cart', 02:12:07 INFO - 'tsvg_static']}, 02:12:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'talos_options': ['--disable-e10s'], 02:12:07 INFO - 'tests': ['tp5o']}, 02:12:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:12:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'talos_options': ['--disable-e10s', 02:12:07 INFO - '--xperf_path', 02:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:07 INFO - 'tests': ['tp5n']}, 02:12:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:12:07 INFO - 'talos_options': ['--xperf_path', 02:12:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:07 INFO - 'tests': ['tp5n']}}, 02:12:07 INFO - 'talos.zip': {'path': '', 02:12:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:12:07 INFO - Running post-action listener: _resource_record_post_action 02:12:07 INFO - [mozharness: 2017-04-07 09:12:07.106670Z] Finished populate-webroot step (success) 02:12:07 INFO - [mozharness: 2017-04-07 09:12:07.106761Z] Running create-virtualenv step. 02:12:07 INFO - Running pre-action listener: _resource_record_pre_action 02:12:07 INFO - Running main action method: create_virtualenv 02:12:07 INFO - Creating virtualenv /builds/slave/test/build/venv 02:12:07 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:12:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:12:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:12:07 INFO - Using real prefix '/tools/python27' 02:12:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:12:07 INFO - Installing distribute.............................................................................................................................................................................................done. 02:12:10 INFO - Installing pip.................done. 02:12:10 INFO - Return code: 0 02:12:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:12:11 INFO - Reading from file tmpfile_stdout 02:12:11 INFO - Output received: 02:12:11 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:12:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:12:11 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:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:12 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:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:13 INFO - 'HOME': '/Users/cltbld', 02:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:13 INFO - 'LOGNAME': 'cltbld', 02:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:13 INFO - 'MOZ_NO_REMOTE': '1', 02:12:13 INFO - 'NO_EM_RESTART': '1', 02:12:13 INFO - 'PAGER': '/bin/cat', 02:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:13 INFO - 'PWD': '/builds/slave/test', 02:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:13 INFO - 'SHELL': '/bin/bash', 02:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:13 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:13 INFO - 'USER': 'cltbld', 02:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:13 INFO - 'XPC_FLAGS': '0x0', 02:12:13 INFO - 'XPC_SERVICE_NAME': '0', 02:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:13 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:12:13 INFO - Cleaning up... 02:12:13 INFO - Return code: 0 02:12:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:12:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:14 INFO - 'HOME': '/Users/cltbld', 02:12:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:14 INFO - 'LOGNAME': 'cltbld', 02:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:14 INFO - 'MOZ_NO_REMOTE': '1', 02:12:14 INFO - 'NO_EM_RESTART': '1', 02:12:14 INFO - 'PAGER': '/bin/cat', 02:12:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:14 INFO - 'PWD': '/builds/slave/test', 02:12:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:14 INFO - 'SHELL': '/bin/bash', 02:12:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:14 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:14 INFO - 'USER': 'cltbld', 02:12:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:14 INFO - 'XPC_FLAGS': '0x0', 02:12:14 INFO - 'XPC_SERVICE_NAME': '0', 02:12:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:14 INFO - Downloading/unpacking psutil>=3.1.1 02:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:12:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:21 INFO - Installing collected packages: psutil 02:12:21 INFO - Running setup.py install for psutil 02:12:21 INFO - building 'psutil._psutil_osx' extension 02:12:21 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:12:21 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:12:22 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:12:22 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:12:22 INFO - building 'psutil._psutil_posix' extension 02:12:22 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:12:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:12:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:12:22 INFO - ^ 02:12:22 INFO - 1 warning generated. 02:12:22 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:12:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:22 INFO - Successfully installed psutil 02:12:22 INFO - Cleaning up... 02:12:22 INFO - Return code: 0 02:12:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:12:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:22 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:22 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:12:22 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:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:22 INFO - 'HOME': '/Users/cltbld', 02:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:22 INFO - 'LOGNAME': 'cltbld', 02:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:22 INFO - 'MOZ_NO_REMOTE': '1', 02:12:22 INFO - 'NO_EM_RESTART': '1', 02:12:22 INFO - 'PAGER': '/bin/cat', 02:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:22 INFO - 'PWD': '/builds/slave/test', 02:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:22 INFO - 'SHELL': '/bin/bash', 02:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:22 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:22 INFO - 'USER': 'cltbld', 02:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:22 INFO - 'XPC_FLAGS': '0x0', 02:12:22 INFO - 'XPC_SERVICE_NAME': '0', 02:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:22 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:12:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:12:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:12:26 INFO - Installing collected packages: mozsystemmonitor 02:12:26 INFO - Running setup.py install for mozsystemmonitor 02:12:26 INFO - Successfully installed mozsystemmonitor 02:12:26 INFO - Cleaning up... 02:12:26 INFO - Return code: 0 02:12:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:12:26 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:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:26 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:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:26 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:26 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:12:26 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:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:26 INFO - 'HOME': '/Users/cltbld', 02:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:26 INFO - 'LOGNAME': 'cltbld', 02:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:26 INFO - 'MOZ_NO_REMOTE': '1', 02:12:26 INFO - 'NO_EM_RESTART': '1', 02:12:26 INFO - 'PAGER': '/bin/cat', 02:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:26 INFO - 'PWD': '/builds/slave/test', 02:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:26 INFO - 'SHELL': '/bin/bash', 02:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:26 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:26 INFO - 'USER': 'cltbld', 02:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:26 INFO - 'XPC_FLAGS': '0x0', 02:12:26 INFO - 'XPC_SERVICE_NAME': '0', 02:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:26 INFO - Downloading/unpacking jsonschema==2.5.1 02:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:12:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:12:29 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:12:29 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:12:29 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:12:29 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:12:29 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:12:29 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:12:30 INFO - Downloading functools32-3.2.3-2.tar.gz 02:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:12:30 INFO - warning: no files found matching '*.txt' 02:12:30 INFO - no previously-included directories found matching 'build' 02:12:30 INFO - no previously-included directories found matching 'dist' 02:12:30 INFO - no previously-included directories found matching '.git*' 02:12:30 INFO - Installing collected packages: jsonschema, functools32 02:12:30 INFO - Running setup.py install for functools32 02:12:30 INFO - warning: no files found matching '*.txt' 02:12:30 INFO - no previously-included directories found matching 'build' 02:12:30 INFO - no previously-included directories found matching 'dist' 02:12:30 INFO - no previously-included directories found matching '.git*' 02:12:30 INFO - Successfully installed jsonschema functools32 02:12:30 INFO - Cleaning up... 02:12:30 INFO - Return code: 0 02:12:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:30 INFO - 'HOME': '/Users/cltbld', 02:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:30 INFO - 'LOGNAME': 'cltbld', 02:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:30 INFO - 'MOZ_NO_REMOTE': '1', 02:12:30 INFO - 'NO_EM_RESTART': '1', 02:12:30 INFO - 'PAGER': '/bin/cat', 02:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:30 INFO - 'PWD': '/builds/slave/test', 02:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:30 INFO - 'SHELL': '/bin/bash', 02:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:30 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:30 INFO - 'USER': 'cltbld', 02:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:30 INFO - 'XPC_FLAGS': '0x0', 02:12:30 INFO - 'XPC_SERVICE_NAME': '0', 02:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:12:30 INFO - Cleaning up... 02:12:30 INFO - Return code: 0 02:12:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:30 INFO - 'HOME': '/Users/cltbld', 02:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:30 INFO - 'LOGNAME': 'cltbld', 02:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:30 INFO - 'MOZ_NO_REMOTE': '1', 02:12:30 INFO - 'NO_EM_RESTART': '1', 02:12:30 INFO - 'PAGER': '/bin/cat', 02:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:30 INFO - 'PWD': '/builds/slave/test', 02:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:30 INFO - 'SHELL': '/bin/bash', 02:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:30 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:30 INFO - 'USER': 'cltbld', 02:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:30 INFO - 'XPC_FLAGS': '0x0', 02:12:30 INFO - 'XPC_SERVICE_NAME': '0', 02:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:30 INFO - Downloading/unpacking blobuploader==1.2.4 02:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:12:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:12:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:35 INFO - Downloading docopt-0.6.1.tar.gz 02:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:12:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:12:35 INFO - Running setup.py install for blobuploader 02:12:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:12:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:12:35 INFO - Running setup.py install for requests 02:12:36 INFO - Running setup.py install for docopt 02:12:36 INFO - Successfully installed blobuploader requests docopt 02:12:36 INFO - Cleaning up... 02:12:36 INFO - Return code: 0 02:12:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:36 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:36 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:12:36 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:12:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:36 INFO - 'HOME': '/Users/cltbld', 02:12:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:36 INFO - 'LOGNAME': 'cltbld', 02:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:36 INFO - 'MOZ_NO_REMOTE': '1', 02:12:36 INFO - 'NO_EM_RESTART': '1', 02:12:36 INFO - 'PAGER': '/bin/cat', 02:12:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:36 INFO - 'PWD': '/builds/slave/test', 02:12:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:36 INFO - 'SHELL': '/bin/bash', 02:12:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:36 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:36 INFO - 'USER': 'cltbld', 02:12:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:36 INFO - 'XPC_FLAGS': '0x0', 02:12:36 INFO - 'XPC_SERVICE_NAME': '0', 02:12:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:12:36 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-5mFdx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:12:36 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-2R8DDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:12:36 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-drxqH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:12:36 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-CbsQxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:12:36 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-KYHpdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-m2rGUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-D0wC0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-9Z8J_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-dGXRHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-UiRUJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-rwBxVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-7WYoPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-36N3mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:12:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-LCWySu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:12:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-PQYT13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:12:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-jbC9oN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:12:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-NuU0ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:12:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:12:38 INFO - Running setup.py install for manifestparser 02:12:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:12:38 INFO - Running setup.py install for mozcrash 02:12:38 INFO - Running setup.py install for mozdebug 02:12:38 INFO - Running setup.py install for mozdevice 02:12:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:12:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:12:38 INFO - Running setup.py install for mozfile 02:12:39 INFO - Running setup.py install for mozhttpd 02:12:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Running setup.py install for mozinfo 02:12:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Running setup.py install for mozInstall 02:12:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Running setup.py install for mozleak 02:12:39 INFO - Running setup.py install for mozlog 02:12:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:12:39 INFO - Running setup.py install for moznetwork 02:12:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Running setup.py install for mozprocess 02:12:40 INFO - Running setup.py install for mozprofile 02:12:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Running setup.py install for mozrunner 02:12:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Running setup.py install for mozscreenshot 02:12:40 INFO - Running setup.py install for moztest 02:12:40 INFO - Running setup.py install for mozversion 02:12:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:12:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:12:40 INFO - Cleaning up... 02:12:40 INFO - Return code: 0 02:12:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:12:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:40 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:40 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:12:40 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:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:40 INFO - 'HOME': '/Users/cltbld', 02:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:40 INFO - 'LOGNAME': 'cltbld', 02:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:40 INFO - 'MOZ_NO_REMOTE': '1', 02:12:40 INFO - 'NO_EM_RESTART': '1', 02:12:40 INFO - 'PAGER': '/bin/cat', 02:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:40 INFO - 'PWD': '/builds/slave/test', 02:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:40 INFO - 'SHELL': '/bin/bash', 02:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:40 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:40 INFO - 'USER': 'cltbld', 02:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:40 INFO - 'XPC_FLAGS': '0x0', 02:12:40 INFO - 'XPC_SERVICE_NAME': '0', 02:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-jVGkcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-kDrrGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-CHr6_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-cIDT4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-GQlIeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-w2_lGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-gY7_Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:12:41 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:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:12:41 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-dxCvox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-piYTK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-MVPJpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-w2HBb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-U_7vaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-gFmNq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-SpPckM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-8Zr0V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-0xD7tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:12:42 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:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:12:42 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-b0UI78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:12:43 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:12:43 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:12:43 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:12:43 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:12:43 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:12:43 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:12:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:48 INFO - Downloading blessings-1.6.tar.gz 02:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:12:48 INFO - Installing collected packages: blessings 02:12:48 INFO - Running setup.py install for blessings 02:12:48 INFO - Successfully installed blessings 02:12:48 INFO - Cleaning up... 02:12:48 INFO - Return code: 0 02:12:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:12:48 INFO - Reading from file tmpfile_stdout 02:12:48 INFO - Current package versions: 02:12:48 INFO - blessings == 1.6 02:12:48 INFO - blobuploader == 1.2.4 02:12:48 INFO - docopt == 0.6.1 02:12:48 INFO - functools32 == 3.2.3-2 02:12:48 INFO - jsonschema == 2.5.1 02:12:48 INFO - manifestparser == 1.1 02:12:48 INFO - mozInstall == 1.12 02:12:48 INFO - mozcrash == 1.0 02:12:48 INFO - mozdebug == 0.1 02:12:48 INFO - mozdevice == 0.49 02:12:48 INFO - mozfile == 1.2 02:12:48 INFO - mozhttpd == 0.7 02:12:48 INFO - mozinfo == 0.9 02:12:48 INFO - mozleak == 0.1 02:12:48 INFO - mozlog == 3.4 02:12:48 INFO - moznetwork == 0.27 02:12:48 INFO - mozprocess == 0.25 02:12:48 INFO - mozprofile == 0.28 02:12:48 INFO - mozrunner == 6.13 02:12:48 INFO - mozscreenshot == 0.1 02:12:48 INFO - mozsystemmonitor == 0.3 02:12:48 INFO - moztest == 0.8 02:12:48 INFO - mozversion == 1.4 02:12:48 INFO - psutil == 3.1.1 02:12:48 INFO - requests == 1.2.3 02:12:48 INFO - wsgiref == 0.1.2 02:12:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:12: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:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12: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:12:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:48 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:48 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:12:48 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:12:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:48 INFO - 'HOME': '/Users/cltbld', 02:12:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:48 INFO - 'LOGNAME': 'cltbld', 02:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:48 INFO - 'MOZ_NO_REMOTE': '1', 02:12:48 INFO - 'NO_EM_RESTART': '1', 02:12:48 INFO - 'PAGER': '/bin/cat', 02:12:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:48 INFO - 'PWD': '/builds/slave/test', 02:12:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:48 INFO - 'SHELL': '/bin/bash', 02:12:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:48 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:48 INFO - 'USER': 'cltbld', 02:12:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:48 INFO - 'XPC_FLAGS': '0x0', 02:12:48 INFO - 'XPC_SERVICE_NAME': '0', 02:12:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 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:12:48 INFO - Cleaning up... 02:12:49 INFO - Return code: 0 02:12:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:49 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 0x104e5eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff650d9e9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, '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.hiLT4EZHAo/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.7XeHIYv61S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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:12:49 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:12:49 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:12:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:12:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:49 INFO - 'HOME': '/Users/cltbld', 02:12:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:49 INFO - 'LOGNAME': 'cltbld', 02:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:49 INFO - 'MOZ_NO_REMOTE': '1', 02:12:49 INFO - 'NO_EM_RESTART': '1', 02:12:49 INFO - 'PAGER': '/bin/cat', 02:12:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:49 INFO - 'PWD': '/builds/slave/test', 02:12:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:49 INFO - 'SHELL': '/bin/bash', 02:12:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:12:49 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:12:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:49 INFO - 'USER': 'cltbld', 02:12:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:49 INFO - 'XPC_FLAGS': '0x0', 02:12:49 INFO - 'XPC_SERVICE_NAME': '0', 02:12:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:12:49 INFO - Cleaning up... 02:12:49 INFO - Return code: 0 02:12:49 INFO - Running post-action listener: _resource_record_post_action 02:12:49 INFO - Running post-action listener: _start_resource_monitoring 02:12:49 INFO - Starting resource monitoring. 02:12:49 INFO - [mozharness: 2017-04-07 09:12:49.299499Z] Finished create-virtualenv step (success) 02:12:49 INFO - [mozharness: 2017-04-07 09:12:49.300471Z] Running install step. 02:12:49 INFO - Running pre-action listener: _resource_record_pre_action 02:12:49 INFO - Running main action method: install 02:12:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:12:49 INFO - Reading from file tmpfile_stdout 02:12:49 INFO - Detecting whether we're running mozinstall >=1.0... 02:12:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:12:49 INFO - Reading from file tmpfile_stdout 02:12:49 INFO - Output received: 02:12:49 INFO - Usage: mozinstall [options] installer 02:12:49 INFO - Options: 02:12:49 INFO - -h, --help show this help message and exit 02:12:49 INFO - -d DEST, --destination=DEST 02:12:49 INFO - Directory to install application into. [default: 02:12:49 INFO - "/builds/slave/test"] 02:12:49 INFO - --app=APP Application being installed. [default: firefox] 02:12:49 INFO - mkdir: /builds/slave/test/build/application 02:12:49 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:12:49 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:13:09 INFO - Reading from file tmpfile_stdout 02:13:09 INFO - Output received: 02:13:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:13:09 INFO - Running post-action listener: _resource_record_post_action 02:13:09 INFO - [mozharness: 2017-04-07 09:13:09.667751Z] Finished install step (success) 02:13:09 INFO - [mozharness: 2017-04-07 09:13:09.667931Z] Running run-tests step. 02:13:09 INFO - Running pre-action listener: _resource_record_pre_action 02:13:09 INFO - Running main action method: run_tests 02:13:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:13:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:13:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:13:09 INFO - 2017-04-07 02:13:09.682 screenresolution[1626:7654] starting screenresolution argv=screenresolution get 02:13:09 INFO - 2017-04-07 02:13:09.685 screenresolution[1626:7654] Display 0: 1600x1200x32@60 02:13:09 INFO - 2017-04-07 02:13:09.690 screenresolution[1627:7656] starting screenresolution argv=screenresolution list 02:13:09 INFO - Available Modes on Display 0 02:13:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:13:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:13:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:13:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:13:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:13:09 INFO - Intel Iris: 02:13:09 INFO - Chipset Model: Intel Iris 02:13:09 INFO - Type: GPU 02:13:09 INFO - Bus: Built-In 02:13:09 INFO - VRAM (Dynamic, Max): 1536 MB 02:13:09 INFO - Vendor: Intel (0x8086) 02:13:09 INFO - Device ID: 0x0a2e 02:13:09 INFO - Revision ID: 0x0009 02:13:09 INFO - Displays: 02:13:09 INFO - Display: 02:13:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:13:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:13:09 INFO - Display Serial Number: 1600x1200 60 02:13:09 INFO - Main Display: Yes 02:13:09 INFO - Mirror: Off 02:13:09 INFO - Online: Yes 02:13:09 INFO - Rotation: Supported 02:13:09 INFO - Return code: 0 02:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:13:09 INFO - Python 2.7.3 02:13:09 INFO - Return code: 0 02:13:09 INFO - grabbing minidump binary from tooltool 02:13:09 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:09 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 0x104c3cd30>, '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 0x104b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c31be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:13:09 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:13:09 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:13:09 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:13:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:13:10 INFO - Return code: 0 02:13:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:13:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:13:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:13:10 INFO - ENV: RUST_BACKTRACE is now 1 02:13:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:13:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:13:10 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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:13:10 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --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:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hiLT4EZHAo/Render', 02:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:10 INFO - 'HOME': '/Users/cltbld', 02:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:10 INFO - 'LOGNAME': 'cltbld', 02:13:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:13:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:10 INFO - 'MOZ_NO_REMOTE': '1', 02:13:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:13:10 INFO - 'NO_EM_RESTART': '1', 02:13:10 INFO - 'PAGER': '/bin/cat', 02:13:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:10 INFO - 'PWD': '/builds/slave/test', 02:13:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:10 INFO - 'RUST_BACKTRACE': '1', 02:13:10 INFO - 'SHELL': '/bin/bash', 02:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XeHIYv61S/Listeners', 02:13:10 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:10 INFO - 'USER': 'cltbld', 02:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:10 INFO - 'XPC_FLAGS': '0x0', 02:13:10 INFO - 'XPC_SERVICE_NAME': '0', 02:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:10 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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:13:10 INFO - mozversion application_buildid: 20170407004017 02:13:10 INFO - mozversion application_changeset: 92ba21762445f89ae0691c4eab0746ca1cb819c2 02:13:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:13:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:13:10 INFO - mozversion application_name: Firefox 02:13:10 INFO - mozversion application_remotingname: firefox-dev 02:13:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:10 INFO - mozversion application_vendor: Mozilla 02:13:10 INFO - mozversion application_version: 54.0a2 02:13:10 INFO - mozversion platform_buildid: 20170407004017 02:13:10 INFO - mozversion platform_changeset: 92ba21762445f89ae0691c4eab0746ca1cb819c2 02:13:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:10 INFO - mozversion platform_version: 54.0a2 02:13:10 INFO - using testdate: 1491556390 02:13:10 INFO - actual date: 1491556390 02:13:10 INFO - starting webserver on 'localhost:49249' 02:13:10 INFO - SUITE-START | Running 2 tests 02:13:10 INFO - TEST-START | dromaeo_css 02:13:10 INFO - operating with platform_type : mac_ 02:13:10 INFO - Initialising browser for dromaeo_css test... 02:13:10 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpAw9GIw/profile http://localhost:49249/getInfo.html) 02:13:13 INFO - PROCESS | 1637 | 2017-04-07 02:13:13.141 plugin-container[1640:7844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9143, name = 'com.apple.tsm.portname' 02:13:13 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:13:13 INFO - PROCESS | 1637 | 2017-04-07 02:13:13.141 plugin-container[1640:7844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 02:13:13 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:13:13 INFO - PROCESS | 1637 | 2017-04-07 02:13:13.141 plugin-container[1640:7844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:13:13 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 02:13:13 INFO - PROCESS | 1637 | colorDepth:24 02:13:13 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 02:13:13 INFO - PROCESS | 1637 | __metrics 02:13:13 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:13:13 INFO - TEST-INFO | 1637: exit 0 02:13:13 INFO - Browser initialized. 02:13:13 INFO - Running cycle 1/1 for dromaeo_css test... 02:13:13 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpAw9GIw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:13:16 INFO - PROCESS | 1641 | 2017-04-07 02:13:16.335 plugin-container[1642:7975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 02:13:16 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:13:16 INFO - PROCESS | 1641 | 2017-04-07 02:13:16.335 plugin-container[1642:7975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 02:13:16 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:13:16 INFO - PROCESS | 1641 | 2017-04-07 02:13:16.335 plugin-container[1642:7975] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:13:26 INFO - PROCESS | 1641 | RSS: Main: 220459008 02:13:26 INFO - PROCESS | 1641 | 02:16:29 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 02:16:31 INFO - PROCESS | 1641 | RSS: Main: 238137344 02:16:31 INFO - PROCESS | 1641 | 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:18:14 INFO - PROCESS | 1641 | 02:18:14 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:14 INFO - PROCESS | 1641 | Full stack: 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:18:14 INFO - PROCESS | 1641 | 02:18:14 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:14 INFO - PROCESS | 1641 | Full stack: 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:18:14 INFO - PROCESS | 1641 | 02:18:14 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:14 INFO - PROCESS | 1641 | Full stack: 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | ************************* 02:18:14 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:18:14 INFO - PROCESS | 1641 | 02:18:14 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:14 INFO - PROCESS | 1641 | Full stack: 02:18:14 INFO - PROCESS | 1641 | ************************* 02:19:34 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 02:19:34 INFO - PROCESS | 1641 | RSS: Main: 246890496 02:19:34 INFO - PROCESS | 1641 | 02:22:36 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 02:22:37 INFO - PROCESS | 1641 | RSS: Main: 254509056 02:22:37 INFO - PROCESS | 1641 | 02:25:39 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 02:25:39 INFO - PROCESS | 1641 | RSS: Main: 251019264 02:25:39 INFO - PROCESS | 1641 | 02:28:42 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 02:28:43 INFO - PROCESS | 1641 | RSS: Main: 256020480 02:28:43 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 02:31:46 INFO - PROCESS | 1641 | RSS: Main: 252321792 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | __start_tp_report 02:31:46 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 02:31:46 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 02:31:46 INFO - PROCESS | 1641 | |i|pagename|runs| 02:31:46 INFO - PROCESS | 1641 | |0;dojo.html;3453.7755045922013;3518.57777066752;3531.028967034066;3540.3273378058166;3587.497750314955;6071.574989750719;6086.239220097488;6110.938890611093;6164.815055548341;6175.7529698811995;6108.602940808844;6111.786087486938;6140.201773769415;6195.31851496335;6199.318075011745;5501.559875209973;5552.305961754781;5566.666000039991;5572.359178694442;5612.130119831432;5606.131049687305;5622.184783206432;5634.633748806335;5636.210930469738;5688.146777983315;5416.133418653017;5430.647007944491;5578.9163162552595;5624.887502249952;5630.5495560355075;5501.119820828648;5526.723663816813;5531.874490912642;5536.252605898192;5551.916721249185;5484.972575137139;5557.555395568385;5569.498745112959;5583.134614134816;5674.347450043257;6179.81460556184;6197.411245931675;6234.314225435199;6253.843653908643;6262.96868515659;6220.751169953196;6300.275468321156;6446.871062578769;6448.871022579569;6450.838729031765;6344.936550634527;6409;6424.967875160641;6485.2541957675;6505.7722979695955;6266.467350275187;6285.528585356116;6291.622502649856;6313.147725057104;6336.746530138743;6754.45964322853;6790.42281406086;6793.864122717518;6799;6812.1144251248015;6261.937380626136;6304.621722696653;6310.1796766420075;6314.17915670969;6326.810195694136;6539.08452816606;6541.411272985454;6551.508636852255;6559.639219842955;6562.278149403562;6349.904751428732;6387.457066149429;6427.325130861286;6429.196350456193;6447.1296374989715;6260.812175634738;6299.716512756845;6370;6386.07401926718;6445.774397896051;5243.659162154448;5253.212018197224;5256.737163141827;5262.657927234717;5267.525922666979;4616.330632058332;4664.533546645308;4671.7430541320555;4680.789364478606;4697.060587882437;5953.166556682068;5995.100734889801;6094.969525152346;6258.624482531062;6287.276963149205;6425.3574642536305;6457.903131453032;6529.836754081091;6584.341565843377;6597.076409302702;6254.030625253093;6278.434940855345;6285.2457705075685;6297.842553936097;6332.303446620868;5573;5618.803341883015;5707.857303567444;5710.71446427677;5812;6423.4861211103935;6623.006549017686;6853.0405743195;6854.485913556603;6859.828504287433;6271.027990661272;6322.715477803602;6412.166418365583;6429.357064293533;6453.677316134081;6784;6788.864222715617;6809.795706128824;6824.249332573314;6829.726810927507;6333.2716737575765;6449.580777249652;6463.806085817433;6466.902996455151;6483.967580162069;5325.680459172462;6225.688715564294;6286.308506064238;6353.904691429725;6414.390632889868;6214.161088253028;6342.49260059185;6350.587211831216;6353.047042943595;6368.235811702625;5188.610854186027;5192.324997750268;6359.8092057238355;6381.7128229228865;6388.265349484869;6116.204893363834;6133.172021777003;6146.661933593696;6247.5939063960695;6363.236411630634;6294.779682711083;6296.150019747459;6336.683165841781;6370.649614271074;6374.58565193261;6350.872982540416;6351.872962540816;6365.427111559982;6370.394812492806;6381.361863813581 02:31:46 INFO - PROCESS | 1641 | |1;ext.html;796.7490240574218;822.3010441125044;833;862.8705694145877;933.1927882381742;38112.428313575285;38828;40524.79737601315;40780.796096019556;41018.97452563683;32405.513917291202;38802.417963730564;39033.804830975874;39246;39321.41017884727;7256.963715181417;7449.180590135081;7801.570913599764;7940.602969851484;7967.960160199191;9555.713328600119;10949;11204.775904481905;11222.102231821435;11225.60710375137;11186.161037922147;11201.159913006557;11219.214654974156;11251.774964500704;11267.323960562351;10126;10464.738381540446;11176.161787865902;11233.21367504279;11236.66290011301;10905.509252083595;10917.508712107974;10964.835527467094;10974.506147223314;10984.670459886216;38868.61131388678;39066.80466597677;39134.60865391367;39154.21691566153;39201.60798392008;3854.3062248795204;3858.826352814102;3885.825137868803;3895.7272990890647;3902.6097390260747;27720.861395693093;28234.43531129366;28648.570271445944;28951.131466056053;29133.271668208254;3105.6117985251844;3116.812991220534;3133.9373212535847;3136.702013308732;3229.11199420159;345422;346443;346720;347923;349326.50673493446;36947.815260923526;44370.55629443664;49658;52943.14698985594;52943.735281323345;54747.083852064985;55021.44978550163;55037.72481137569;55103;55233.72383138059;14947.177905215318;15020.924895375672;15060.698786024219;15082.924585377223;15085.3211605478;12917.354132293347;12927.870721292855;12938.288394138224;12969.935150324189;12976.740465190644;12579.93710031444;12638.55765048219;12696;12731.427085781163;12786.808197877039;4871.34236878017;5074.517920797518;5451.100568406168;5479.890402191933;5484.945150548443;957.3585697582637;969.413504829568;1011.1708399112657;1068.4550879051585;1155.9364234967159;24696.876515617307;26925.865370673022;26983;27060.729392706216;27312.726872731415;1044.300318786415;1205.626255860686;1250.5623031938749;1259.6850787303174;1300.7593595184921;12652.304123273125;13985.090969086978;14020.878329733598;14140.363683634261;14168.716625667428;456836;457658.711706446;457890;458723.41276587476;458848;55359.723201383735;55656;55777.72111139418;55850.72074639601;55878.44121558814;1404.2767974493145;1452.186775405755;1452.2375752729902;1456.8106146201137;1459.408939379553;25600.671912651236;25998.61002085008;26281.474370512864;26585.20244392671;27473;36359.45460818037;45471;45616.631501055024;45628.54371456243;45693.8576535576;481008;482901;484561;484820;485761;29326.41347173002;29673.703262967094;29814.701852981194;30165.849170754005;30861.691383085883;37141.62858371382;37992.240155197294;39141;40462;40669.18661626692;22406.66390004184;27429.31426714348;27852.86073569619;28099.71900281053;28658.57012144861;27505.85076920841;28350.858245708638;28559.714402855705;28565.28586785264;28741.28146796263;32288.192795180308;32472.89289776541;32475.188120296236;32876.835615821765;32962.340753184326;28223.858880706288;28423.147305580867;28692;28896.03429796423;29156.56265155982;23227.419314517272;23236.883815580815;23356.883215583814;23579.646305305778;24164 02:31:46 INFO - PROCESS | 1641 | |2;jquery.html;367.99264014719705;405.355484779201;406.5588836112818;417.95193552741426;445.4543184598867;20307.08618112185;21090.789092109073;21264.36206913795;21348.35954921351;21691.891540542296;20434.978251087425;21195.894020529915;21335.57328853422;21491.57016859662;22068.558628827413;5661.320641523023;6360.363963603637;6609.702563384635;6616.338366163369;6651.235107962573;5386.461353864602;6314.494840412779;7065.116860392451;7384.778456646309;7491.6254187290415;7298.890516642228;7471.327580517753;7491.812704682372;7508.587027713474;7515.924840751577;7257.927420725778;7470.439717021218;7525.924740752577;7535.623218839063;7538.962305188466;7251.637418129125;7356.639021780988;7360.595167265798;7370.77887663371;7380.372668323199;20781.792182078138;24332.39169020771;24591.87704061486;25311.62032569513;25673;2860.313524754065;2863.9284017899513;2889.6821349651523;2937.1922721251613;2988.282812125096;15240.314185861664;15484.767728484081;15523.534293971199;15711.450099246471;16049.679006419807;2025.1798021801194;2047.2732180076007;2078.771335153132;2082.916683332665;2084.801943815335;127836.72163278342;157862;161415;168735.1563242176;170274;18321;20620.484487887923;21035.579288414145;21135.154593816074;21157.365279041653;28571.85714071458;30685;30821;31075.378492430023;31079;10008.249381296286;10071.647492337734;10218.591256349811;10244.846327305097;10394.324368915995;6746;7684.346830519356;7842.764717058498;8400.033996090528;8439.831203375898;7658.502197357266;9307.72076837696;9396.671116511026;9436.528173591292;9516.714498565054;3656.8720094796554;3678.319510890494;3972.980135099306;4040.7979601019833;4213.052063285736;799.2846402469758;816.6284340625001;873.3275377958936;885.1900511032462;915.3272344826547;20602.793972060386;21061.78938210629;21138.048787804586;21145.365639030853;21214.46963825886;772.8802035684478;822.0258993093204;907.7412937312835;911.1753862754218;944.7826999790087;9572.186364158984;10669.573217071385;10742.946285268523;10819.78360432787;10832.78334433307;533393;682940.5852970703;738105;744153;753353;149294;149878;151003.2449837766;151887.24056379648;152230;1129.164418330446;1143.5825923537848;1166.0787977497682;1168.39243593331;1172.7068232941765;14605;16766.832331676527;17468.03925784043;17590.29638814484;18033.278668853098;57975.71012144912;64114.67942660257;64803;64896.67551662211;65695.67152164209;72650.54698906098;89298.8745393908;91724.54137729536;91738.08261917296;93565;9548.37935534188;10392.948035259775;10975.176861735243;11115.11079113685;11174.329540227613;8808.647654093764;8817.955910220664;9549.9045009549;11298.322100673986;13006.934965325112;5896.764129434775;11108.389038602954;11115.666530004113;11152.776944461228;11391.430428478709;8184.754457366289;10188.439635820101;10498.107660848607;10506.317089389166;10737.838932415863;9284.396514226553;10605.575776968835;10766.169751142168;10971.670849874517;11386.373749443861;9263.907360926305;11512.42437878086;11537.596184133516;11624.593139240085;11774.823377649513;10673.092787113183;11067.72330691739;11096.223264371416;11105.38920359388;11176.497057632263 02:31:46 INFO - PROCESS | 1641 | |3;mootools.html;1526.8702160316373;1536.6696160325528;1547.512533551931;1552.6040859580805;1580.1467207707835;7906.723264685737;8931.821363572724;10979.451027448615;10999.450027498624;11161.38612376319;10833.349999000066;10942.069924056445;10990.835137472925;11010.119190464764;11047.447627618627;2346.4251258441664;2369.9881500592473;2381;2382.201962342617;2383.916562920298;1958.4026871804165;2276.169198242638;2369.5853225685523;2380.4048987753063;2385.320183747632;1960.5882764619448;2289.1415719105335;2331.0442718485424;2350.74141844397;2363.6809030780882;1876.652819228447;2335.521218150275;2336.030547322859;2348.7064116985375;2349.776771206741;2333.381653861728;2336.8130549555995;2337.1936681844904;2337.9883100584557;2348.377679914824;10902.291351062155;10906.291091079054;10954.068904143158;10958.726031849188;10977.341359518454;5452.863678408032;5607.551395888319;5614.242077319551;5618.522425593788;5624.268845050158;3122.172624254574;3209.8555564999633;3307.140143562667;3364.7981121132566;3396.7961922284744;1081.2485322700727;1086.4513420722565;1119.9328040317607;1122.5397586989295;1136.2443974756777;10889.891101089046;13414.195148291134;14794.482193123189;14863.182524961123;14955.055842740374;3903.921921561553;3952.4861767970556;4323.978380108079;4489.820407183742;4599.954000460019;11491.310521368745;11588.246763960316;11617.53529858813;11627.88372116268;11628.069754419796;5540;5601.7759289628775;5603.327600687943;5605.915911261334;5621.353544342372;4062.4921884764403;4891.29076283944;5089.770960306795;5092;5103.515166059218;3675.61406052366;3769.5853456119803;3791.7724936503896;3837.175007373428;3873.283442563135;989.0702739424918;990.7473594233425;996.6262651505685;997.6159178716247;999.6801023672355;913.4656226107669;919.1773362840221;937.7843096087938;945.8864936207699;972.9173020293213;6375.840603984845;6441.420272175526;6594.109795177686;6610.669466526654;6678.165229346332;867.544539116956;994.2096033653263;1001.0790073132736;1055.8257887448485;1058.941058941059;4129.566395528486;4228.154369126187;4233.745975241495;4350.042990542076;4380.211561918885;10652.414117223472;11175.44122793857;13086.411111500005;13515.391807368693;13572.864271357357;12737.490500379881;13506.05457617977;13506.864931350561;13523.39144738489;13525.458981640624;9970.40177589347;10192.082712555906;10247.026532479402;10264.743381415525;10313.12338451225;6655.667216639052;6718.563293385914;6730.764423245163;6832.214295355899;6888.758893438706;9699.272554558578;11813.881861181279;11996.820047699466;12141.514339426323;12157.75684486323;11010.39442830652;11297.152713546353;12200.877991219973;12321;12398.752024959269;9877.06167914047;10233.590656373663;10347.482625868526;10354.637587684696;10395.896041039492;10004.29969902129;10155.035271649182;10185.643502477378;10242.692719218436;10302.690919272434;8665.306775457822;8669.349798764992;8702.521361325187;8772.166644168794;8933.508657023925;7353.926460735324;8369.95815020921;8481.15188481147;8590.78523036929;8609.354298427517;7769.92230077692;7780.105287891738;7791.92208077912;7822.256885595859;7856.450048496779;5734.910367030251;5959.255093113361;5995.91006134917;6024;6066.939330606814;5937.762489500372;5940.970295148497;5961.880762384815;5973.49225315853;6076.361981991828 02:31:46 INFO - PROCESS | 1641 | |4;prototype.html;326.40268308994536;355.63369729178953;381.96297053499745;391.3269177015534;396.0731887383522;4535.160995215883;5054.292399064129;5207.895842083156;5357.107162132444;5390.299261096062;4318.978405107978;5160.91620759641;5294.523492885649;5373.623846330758;5423.322084739408;4158.189153115129;4230.9788451057775;4261.254280500915;4412.5587441255775;4447.35513350564;3433.1588760753525;4286.992556749162;4322.632576231024;4371.431713877191;4437.7352454550455;4163.646090082347;4251.744895306276;4389.561043895601;4406.69153159279;4464.174127786354;4051.7568945863195;4124.463819703435;4351.282038463682;4363.912721745547;4388.990532177617;3878.4376265441633;4036.233115708006;4036.9192616147807;4083.3874918762126;4089.078133277355;5154.742262886841;5198.322863725184;5220.843374698765;5228.9345708445335;5231.869203269911;3902.570717221103;4345.067079721176;5001.774920128603;5064.341635587387;5096.572959571326;4889.921783242108;5507.5593952484105;5706.86511999602;5760.452756988079;5826.833813823095;3822.4648549203134;4357.542458041891;4497.707649002804;4536.591706746376;4610.631149508032;5185.562785490813;5777.959967205901;5954.868574970785;6024.126501657279;6041.607295525777;4200;4352.303631419021;4517.628245171246;4541.704789188692;4581.473130589959;3980.601939805996;4639.628829693616;4676.836310729108;4695.319178719066;4734.26618874075;4557.316402539645;4740.293494342034;4856.2958371036;4981.601471882241;5012.548870601663;5370.087085195526;5458.072127738294;5643.52030077448;5701.4298570142655;5747.655140691572;1365.1399618240644;1416.4489883577671;1428.8571142885628;1462.6050966239056;1589.348367169455;1025.34406932803;1038.1383451735041;1139.9259048161844;1197.2935967779051;1225.9570915017932;4388.758618276025;4400.647948164139;4684.484706682262;5034.622403319766;5230.63385563007;5195.870103247449;5229.843104706865;5275.050490911673;5802.564807639444;5842.912356314659;4874.707517548958;5019.644695932078;5183.948160518422;5261.368635763656;5363.946360536423;2559.9104031359184;2757.9724202758116;2834;2942.911712648624;3006.969930300713;5843.415658434123;5970.820875373659;5971.0744834550715;6022.964613708826;6124.969375153096;5137.255098010767;5357.919631205535;5584.609077364546;5636.549076073904;5894.616849904828;4776.8328108516735;5193.454687257935;5504.339479262514;5816.941830581809;6098.969505152446;5062.519060689228;5320.920186197288;5921.200637913998;5925.229720136527;5954.672493012926;4165.416841642112;5554.028869358428;5783.826485205451;5801.607614172653;5929.983805506148;4847.975760121177;5672.007398705127;6000.609960352563;6124.539635106151;6159.661218632839;4366.934495982627;4648.349231107715;4696.248600224085;4700.247960326331;4717.386739723814;4719.669623126486;5675.659460432387;5773.1051686988585;5861.9706901465215;5906.751286792333;4775.42694876617;4901.573721008123;5377.193420986883;5503.798480607789;5561.833145005657;5299.198272587339;5391.649542779707;5667.838093190969;5728.942710572841;5779.68211748341;4992.700437973734;5390.014236830837;5491.725413729378;5566.721663916707;5617.578681598815;5263.815516560738;5540.279763630702;5711.657300561979;5716.399778023248;5752.453516915886;5171.612129090408;5238.449962753856;5514.338279406497;5603;5612.775488980558 02:31:46 INFO - PROCESS | 1641 | |5;yui.html;679.9898001529976;711.8540699156673;789.9605019749015;815.9755207343782;837.7486753973808;13007.544735934243;16076.437324693637;16140.95083819551;16320.918395408036;16374.345026198953;15104.960830287908;15311.540653780405;15318.387264509407;15326.080435173897;15339.539813805604;914.7210100919234;923.9630414783434;931.776373670321;939.1922946266205;947.9905200947971;933.6032186320821;934;936.0779632062413;937.1986951156765;937.4890684576887;971.621067783565;990.7374545745383;992.0377234082928;992.3500107429645;993.9006099390075;989.45579931038;989.5645915797027;989.8713167288242;991.7818080022383;993.3096497933939;720.121451828768;732.721565804996;733.069002366991;734.5519233267703;734.867723809714;16140.273687684083;16393.34426622934;16425.342986280535;16472.341106355732;16577.91711041449;13684.178949263076;13805.240711760849;13995.65010874726;14001;14167.291635418187;11508.827367589492;11831.645050648494;11844.407779610985;11868.881311186864;11976.70058248542;3591;3627.818609069536;3642.508261384732;3724.0131365188245;3820.0449887528116;52213.73893130548;73399;96947.51526242394;97141.51429242949;105472.47263763708;8951.73144805657;10736.677899663022;10920.726981825359;10943.35849622547;10988.340699558052;17551.824481755273;20967.475813104793;21072.367828965154;21284.467888302916;21462.785372146078;7895.131535531087;7917.643706033243;7927.722529711433;8035.7991050223045;8169.9591502043295;4329;4387.956120438818;4413.426254586947;4437.556244375602;4451.799669014902;3338.4157772389735;3389.237421580174;3405.7615966882577;3420.931581368359;3439.673231043047;612.2836281550597;615.972281247345;618.6597371445687;620.0667994667996;625.1560393468781;658.1969996604136;675.6148995072859;676.2189670930084;686.1457485430572;693.6427739714052;10188.949055254676;10206.921226818335;10261.897381026094;10559.208059395576;10662.466876656292;629.9685015749194;660.7059858362983;684.3567046975926;684.4114061906756;684.6302996381899;3945.112349721347;4710.952890471051;4988.850334489971;5002.824901128443;5004.774785134678;93472;96424;96855;97534;98665;58975;74856;81557.59221203974;84117;85765.57117214374;60702.99999999912;75806.48387032228;76373.6181319101;77199;78070.6096469514;10690.839637405601;10929.836052459379;11051.301115241637;11129.888701113208;11164.553417863195;47147.764261179844;47491.76254118846;47726.76136619433;48460.03079938452;48736.53790386294;61848;66466.66766666136;69227;70170.29829701637;72361.91457128253;12492;12658.809658225216;12781.16922400041;12782.808257876326;12971.805422918853;14483.637909052359;14582.823097800128;14630;14802.777958330418;14906.105633662015;11292.49183786748;11913.880861191625;13312;13765.849661773995;13852.722945541234;9908.900910990798;11812.3503207321;12003.81994270104;12012;12171.75656486883;11319.377434241196;11717.707057323634;12095.818562721743;12332.938335308625;12454.81317780216;8890.422122562013;8894.777630559029;9004.864927025968;9125.17873391398;9279.747234123577;8291.549589443823;9992.750181245527;10167.44079075658;10310.896891030994;10339.327943683636 02:31:46 INFO - PROCESS | 1641 | __end_tp_report 02:31:46 INFO - PROCESS | 1641 | __start_cc_report 02:31:46 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,418 02:31:46 INFO - PROCESS | 1641 | __end_cc_report 02:31:46 INFO - PROCESS | 1641 | __startTimestamp1491557506785__endTimestamp 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | ------- Summary: start ------- 02:31:46 INFO - PROCESS | 1641 | Number of tests: 6 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6045.58 Median:6286.31 stddev:656.95 (10.5%) stddev-sans-first:626.72 02:31:46 INFO - PROCESS | 1641 | Values: 3453.8 3518.6 3531.0 3540.3 3587.5 6071.6 6086.2 6110.9 6164.8 6175.8 6108.6 6111.8 6140.2 6195.3 6199.3 5501.6 5552.3 5566.7 5572.4 5612.1 5606.1 5622.2 5634.6 5636.2 5688.1 5416.1 5430.6 5578.9 5624.9 5630.5 5501.1 5526.7 5531.9 5536.3 5551.9 5485.0 5557.6 5569.5 5583.1 5674.3 6179.8 6197.4 6234.3 6253.8 6263.0 6220.8 6300.3 6446.9 6448.9 6450.8 6344.9 6409.0 6425.0 6485.3 6505.8 6266.5 6285.5 6291.6 6313.1 6336.7 6754.5 6790.4 6793.9 6799.0 6812.1 6261.9 6304.6 6310.2 6314.2 6326.8 6539.1 6541.4 6551.5 6559.6 6562.3 6349.9 6387.5 6427.3 6429.2 6447.1 6260.8 6299.7 6370.0 6386.1 6445.8 5243.7 5253.2 5256.7 5262.7 5267.5 4616.3 4664.5 4671.7 4680.8 4697.1 5953.2 5995.1 6095.0 6258.6 6287.3 6425.4 6457.9 6529.8 6584.3 6597.1 6254.0 6278.4 6285.2 6297.8 6332.3 5573.0 5618.8 5707.9 5710.7 5812.0 6423.5 6623.0 6853.0 6854.5 6859.8 6271.0 6322.7 6412.2 6429.4 6453.7 6784.0 6788.9 6809.8 6824.2 6829.7 6333.3 6449.6 6463.8 6466.9 6484.0 5325.7 6225.7 6286.3 6353.9 6414.4 6214.2 6342.5 6350.6 6353.0 6368.2 5188.6 5192.3 6359.8 6381.7 6388.3 6116.2 6133.2 6146.7 6247.6 6363.2 6294.8 6296.2 6336.7 6370.6 6374.6 6350.9 6351.9 6365.4 6370.4 6381.4 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:56138.25 Median:30513.77 stddev:115279.31 (377.8%) stddev-sans-first:115527.81 02:31:46 INFO - PROCESS | 1641 | Values: 796.7 822.3 833.0 862.9 933.2 38112.4 38828.0 40524.8 40780.8 41019.0 32405.5 38802.4 39033.8 39246.0 39321.4 7257.0 7449.2 7801.6 7940.6 7968.0 9555.7 10949.0 11204.8 11222.1 11225.6 11186.2 11201.2 11219.2 11251.8 11267.3 10126.0 10464.7 11176.2 11233.2 11236.7 10905.5 10917.5 10964.8 10974.5 10984.7 38868.6 39066.8 39134.6 39154.2 39201.6 3854.3 3858.8 3885.8 3895.7 3902.6 27720.9 28234.4 28648.6 28951.1 29133.3 3105.6 3116.8 3133.9 3136.7 3229.1 345422.0 346443.0 346720.0 347923.0 349326.5 36947.8 44370.6 49658.0 52943.1 52943.7 54747.1 55021.4 55037.7 55103.0 55233.7 14947.2 15020.9 15060.7 15082.9 15085.3 12917.4 12927.9 12938.3 12969.9 12976.7 12579.9 12638.6 12696.0 12731.4 12786.8 4871.3 5074.5 5451.1 5479.9 5484.9 957.4 969.4 1011.2 1068.5 1155.9 24696.9 26925.9 26983.0 27060.7 27312.7 1044.3 1205.6 1250.6 1259.7 1300.8 12652.3 13985.1 14020.9 14140.4 14168.7 456836.0 457658.7 457890.0 458723.4 458848.0 55359.7 55656.0 55777.7 55850.7 55878.4 1404.3 1452.2 1452.2 1456.8 1459.4 25600.7 25998.6 26281.5 26585.2 27473.0 36359.5 45471.0 45616.6 45628.5 45693.9 481008.0 482901.0 484561.0 484820.0 485761.0 29326.4 29673.7 29814.7 30165.8 30861.7 37141.6 37992.2 39141.0 40462.0 40669.2 22406.7 27429.3 27852.9 28099.7 28658.6 27505.9 28350.9 28559.7 28565.3 28741.3 32288.2 32472.9 32475.2 32876.8 32962.3 28223.9 28423.1 28692.0 28896.0 29156.6 23227.4 23236.9 23356.9 23579.6 24164.0 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:41105.37 Median:27122.43 stddev:116674.01 (430.2%) stddev-sans-first:116961.21 02:31:46 INFO - PROCESS | 1641 | Values: 368.0 405.4 406.6 418.0 445.5 20307.1 21090.8 21264.4 21348.4 21691.9 20435.0 21195.9 21335.6 21491.6 22068.6 5661.3 6360.4 6609.7 6616.3 6651.2 5386.5 6314.5 7065.1 7384.8 7491.6 7298.9 7471.3 7491.8 7508.6 7515.9 7257.9 7470.4 7525.9 7535.6 7539.0 7251.6 7356.6 7360.6 7370.8 7380.4 20781.8 24332.4 24591.9 25311.6 25673.0 2860.3 2863.9 2889.7 2937.2 2988.3 15240.3 15484.8 15523.5 15711.5 16049.7 2025.2 2047.3 2078.8 2082.9 2084.8 127836.7 157862.0 161415.0 168735.2 170274.0 18321.0 20620.5 21035.6 21135.2 21157.4 28571.9 30685.0 30821.0 31075.4 31079.0 10008.2 10071.6 10218.6 10244.8 10394.3 6746.0 7684.3 7842.8 8400.0 8439.8 7658.5 9307.7 9396.7 9436.5 9516.7 3656.9 3678.3 3973.0 4040.8 4213.1 799.3 816.6 873.3 885.2 915.3 20602.8 21061.8 21138.0 21145.4 21214.5 772.9 822.0 907.7 911.2 944.8 9572.2 10669.6 10742.9 10819.8 10832.8 533393.0 682940.6 738105.0 744153.0 753353.0 149294.0 149878.0 151003.2 151887.2 152230.0 1129.2 1143.6 1166.1 1168.4 1172.7 14605.0 16766.8 17468.0 17590.3 18033.3 57975.7 64114.7 64803.0 64896.7 65695.7 72650.5 89298.9 91724.5 91738.1 93565.0 9548.4 10392.9 10975.2 11115.1 11174.3 8808.6 8818.0 9549.9 11298.3 13006.9 5896.8 11108.4 11115.7 11152.8 11391.4 8184.8 10188.4 10498.1 10506.3 10737.8 9284.4 10605.6 10766.2 10971.7 11386.4 9263.9 11512.4 11537.6 11624.6 11774.8 10673.1 11067.7 11096.2 11105.4 11176.5 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6550.73 Median:2383.06 stddev:4065.87 (170.6%) stddev-sans-first:4059.75 02:31:46 INFO - PROCESS | 1641 | Values: 1526.9 1536.7 1547.5 1552.6 1580.1 7906.7 8931.8 10979.5 10999.5 11161.4 10833.3 10942.1 10990.8 11010.1 11047.4 2346.4 2370.0 2381.0 2382.2 2383.9 1958.4 2276.2 2369.6 2380.4 2385.3 1960.6 2289.1 2331.0 2350.7 2363.7 1876.7 2335.5 2336.0 2348.7 2349.8 2333.4 2336.8 2337.2 2338.0 2348.4 10902.3 10906.3 10954.1 10958.7 10977.3 5452.9 5607.6 5614.2 5618.5 5624.3 3122.2 3209.9 3307.1 3364.8 3396.8 1081.2 1086.5 1119.9 1122.5 1136.2 10889.9 13414.2 14794.5 14863.2 14955.1 3903.9 3952.5 4324.0 4489.8 4600.0 11491.3 11588.2 11617.5 11627.9 11628.1 5540.0 5601.8 5603.3 5605.9 5621.4 4062.5 4891.3 5089.8 5092.0 5103.5 3675.6 3769.6 3791.8 3837.2 3873.3 989.1 990.7 996.6 997.6 999.7 913.5 919.2 937.8 945.9 972.9 6375.8 6441.4 6594.1 6610.7 6678.2 867.5 994.2 1001.1 1055.8 1058.9 4129.6 4228.2 4233.7 4350.0 4380.2 10652.4 11175.4 13086.4 13515.4 13572.9 12737.5 13506.1 13506.9 13523.4 13525.5 9970.4 10192.1 10247.0 10264.7 10313.1 6655.7 6718.6 6730.8 6832.2 6888.8 9699.3 11813.9 11996.8 12141.5 12157.8 11010.4 11297.2 12200.9 12321.0 12398.8 9877.1 10233.6 10347.5 10354.6 10395.9 10004.3 10155.0 10185.6 10242.7 10302.7 8665.3 8669.3 8702.5 8772.2 8933.5 7353.9 8370.0 8481.2 8590.8 8609.4 7769.9 7780.1 7791.9 7822.3 7856.5 5734.9 5959.3 5995.9 6024.0 6066.9 5937.8 5941.0 5961.9 5973.5 6076.4 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4686.22 Median:5063.43 stddev:1329.11 (26.2%) stddev-sans-first:1291.92 02:31:46 INFO - PROCESS | 1641 | Values: 326.4 355.6 382.0 391.3 396.1 4535.2 5054.3 5207.9 5357.1 5390.3 4319.0 5160.9 5294.5 5373.6 5423.3 4158.2 4231.0 4261.3 4412.6 4447.4 3433.2 4287.0 4322.6 4371.4 4437.7 4163.6 4251.7 4389.6 4406.7 4464.2 4051.8 4124.5 4351.3 4363.9 4389.0 3878.4 4036.2 4036.9 4083.4 4089.1 5154.7 5198.3 5220.8 5228.9 5231.9 3902.6 4345.1 5001.8 5064.3 5096.6 4889.9 5507.6 5706.9 5760.5 5826.8 3822.5 4357.5 4497.7 4536.6 4610.6 5185.6 5778.0 5954.9 6024.1 6041.6 4200.0 4352.3 4517.6 4541.7 4581.5 3980.6 4639.6 4676.8 4695.3 4734.3 4557.3 4740.3 4856.3 4981.6 5012.5 5370.1 5458.1 5643.5 5701.4 5747.7 1365.1 1416.4 1428.9 1462.6 1589.3 1025.3 1038.1 1139.9 1197.3 1226.0 4388.8 4400.6 4684.5 5034.6 5230.6 5195.9 5229.8 5275.1 5802.6 5842.9 4874.7 5019.6 5183.9 5261.4 5363.9 2559.9 2758.0 2834.0 2942.9 3007.0 5843.4 5970.8 5971.1 6023.0 6125.0 5137.3 5357.9 5584.6 5636.5 5894.6 4776.8 5193.5 5504.3 5816.9 6099.0 5062.5 5320.9 5921.2 5925.2 5954.7 4165.4 5554.0 5783.8 5801.6 5930.0 4848.0 5672.0 6000.6 6124.5 6159.7 4366.9 4648.3 4696.2 4700.2 4717.4 4719.7 5675.7 5773.1 5862.0 5906.8 4775.4 4901.6 5377.2 5503.8 5561.8 5299.2 5391.6 5667.8 5728.9 5779.7 4992.7 5390.0 5491.7 5566.7 5617.6 5263.8 5540.3 5711.7 5716.4 5752.5 5171.6 5238.4 5514.3 5603.0 5612.8 02:31:46 INFO - PROCESS | 1641 | 02:31:46 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19104.69 Median:47609.26 stddev:26541.11 (55.7%) stddev-sans-first:26579.50 02:31:46 INFO - PROCESS | 1641 | Values: 680.0 711.9 790.0 816.0 837.7 13007.5 16076.4 16141.0 16320.9 16374.3 15105.0 15311.5 15318.4 15326.1 15339.5 914.7 924.0 931.8 939.2 948.0 933.6 934.0 936.1 937.2 937.5 971.6 990.7 992.0 992.4 993.9 989.5 989.6 989.9 991.8 993.3 720.1 732.7 733.1 734.6 734.9 16140.3 16393.3 16425.3 16472.3 16577.9 13684.2 13805.2 13995.7 14001.0 14167.3 11508.8 11831.6 11844.4 11868.9 11976.7 3591.0 3627.8 3642.5 3724.0 3820.0 52213.7 73399.0 96947.5 97141.5 105472.5 8951.7 10736.7 10920.7 10943.4 10988.3 17551.8 20967.5 21072.4 21284.5 21462.8 7895.1 7917.6 7927.7 8035.8 8170.0 4329.0 4388.0 4413.4 4437.6 4451.8 3338.4 3389.2 3405.8 3420.9 3439.7 612.3 616.0 618.7 620.1 625.2 658.2 675.6 676.2 686.1 693.6 10188.9 10206.9 10261.9 10559.2 10662.5 630.0 660.7 684.4 684.4 684.6 3945.1 4711.0 4988.9 5002.8 5004.8 93472.0 96424.0 96855.0 97534.0 98665.0 58975.0 74856.0 81557.6 84117.0 85765.6 60703.0 75806.5 76373.6 77199.0 78070.6 10690.8 10929.8 11051.3 11129.9 11164.6 47147.8 47491.8 47726.8 48460.0 48736.5 61848.0 66466.7 69227.0 70170.3 72361.9 12492.0 12658.8 12781.2 12782.8 12971.8 14483.6 14582.8 14630.0 14802.8 14906.1 11292.5 11913.9 13312.0 13765.8 13852.7 9908.9 11812.4 12003.8 12012.0 12171.8 11319.4 11717.7 12095.8 12332.9 12454.8 8890.4 8894.8 9004.9 9125.2 9279.7 8291.5 9992.8 10167.4 10310.9 10339.3 02:31:46 INFO - PROCESS | 1641 | -------- Summary: end -------- 02:31:46 INFO - PROCESS | 1641 | 02:31:47 INFO - TEST-INFO | 1641: exit 0 02:31:47 INFO - TEST-OK | dromaeo_css | took 1117319ms 02:31:47 INFO - TEST-START | kraken 02:31:47 INFO - operating with platform_type : mac_ 02:31:47 INFO - Initialising browser for kraken test... 02:31:47 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpMHyg4g/profile http://localhost:49249/getInfo.html) 02:31:50 INFO - PROCESS | 1651 | 2017-04-07 02:31:50.456 plugin-container[1653:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 02:31:50 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:31:50 INFO - PROCESS | 1651 | 2017-04-07 02:31:50.456 plugin-container[1653:10361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 02:31:50 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:31:50 INFO - PROCESS | 1651 | 2017-04-07 02:31:50.457 plugin-container[1653:10361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:31:50 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 02:31:50 INFO - PROCESS | 1651 | colorDepth:24 02:31:50 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 02:31:50 INFO - PROCESS | 1651 | __metrics 02:31:50 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 02:31:51 INFO - TEST-INFO | 1651: exit 0 02:31:51 INFO - Browser initialized. 02:31:51 INFO - Running cycle 1/1 for kraken test... 02:31:51 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpMHyg4g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:31:53 INFO - PROCESS | 1654 | 2017-04-07 02:31:53.791 plugin-container[1655:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:31:53 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:31:53 INFO - PROCESS | 1654 | 2017-04-07 02:31:53.792 plugin-container[1655:10488] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 02:31:53 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:31:53 INFO - PROCESS | 1654 | 2017-04-07 02:31:53.792 plugin-container[1655:10488] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:32:03 INFO - PROCESS | 1654 | RSS: Main: 220958720 02:32:03 INFO - PROCESS | 1654 | 02:32:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 02:32:07 INFO - PROCESS | 1654 | RSS: Main: 235630592 02:32:07 INFO - PROCESS | 1654 | 02:32:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 02:32:10 INFO - PROCESS | 1654 | RSS: Main: 243306496 02:32:10 INFO - PROCESS | 1654 | 02:32:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 02:32:14 INFO - PROCESS | 1654 | RSS: Main: 246980608 02:32:14 INFO - PROCESS | 1654 | 02:32:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 02:32:17 INFO - PROCESS | 1654 | RSS: Main: 247382016 02:32:17 INFO - PROCESS | 1654 | 02:32:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:32:20 INFO - PROCESS | 1654 | RSS: Main: 248664064 02:32:20 INFO - PROCESS | 1654 | 02:32:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 02:32:24 INFO - PROCESS | 1654 | RSS: Main: 252547072 02:32:24 INFO - PROCESS | 1654 | 02:32:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 02:32:27 INFO - PROCESS | 1654 | RSS: Main: 252538880 02:32:27 INFO - PROCESS | 1654 | 02:32:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 02:32:31 INFO - PROCESS | 1654 | RSS: Main: 252231680 02:32:31 INFO - PROCESS | 1654 | 02:32:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:32:33 INFO - PROCESS | 1654 | RSS: Main: 251539456 02:32:33 INFO - PROCESS | 1654 | 02:32:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:32:36 INFO - PROCESS | 1654 | RSS: Main: 251392000 02:32:36 INFO - PROCESS | 1654 | 02:32:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:32:39 INFO - PROCESS | 1654 | RSS: Main: 251441152 02:32:39 INFO - PROCESS | 1654 | 02:32:41 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:32:42 INFO - PROCESS | 1654 | RSS: Main: 251813888 02:32:42 INFO - PROCESS | 1654 | 02:32:45 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:32:46 INFO - PROCESS | 1654 | RSS: Main: 251625472 02:32:46 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 02:32:48 INFO - PROCESS | 1654 | RSS: Main: 253620224 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | __start_tp_report 02:32:48 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 02:32:48 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 02:32:48 INFO - PROCESS | 1654 | |i|pagename|runs| 02:32:48 INFO - PROCESS | 1654 | |0;ai-astar;110;121;99;174;178;132;119;119;119;149 02:32:48 INFO - PROCESS | 1654 | |1;audio-beat-detection;137;114;112;146;114;116;113;124;161;144 02:32:48 INFO - PROCESS | 1654 | |2;audio-dft;191;195;193;200;197;203;230;287;199;200 02:32:48 INFO - PROCESS | 1654 | |3;audio-fft;86;65;83;88;65;66;74;87;94;62 02:32:48 INFO - PROCESS | 1654 | |4;audio-oscillator;92;86;110;103;105;104;66;90;101;97 02:32:48 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;103;110;103;88;83;90;85;136;138 02:32:48 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;97;96;128;145;96;150;100;97;100 02:32:48 INFO - PROCESS | 1654 | |7;imaging-desaturate;90;94;90;99;139;91;93;91;91;93 02:32:48 INFO - PROCESS | 1654 | |8;json-parse-financial;65;64;66;64;68;69;68;68;68;66 02:32:48 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;56;57;68;81;83;85;50;60;88 02:32:48 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;87;85;86;89;103;78;81;99;111 02:32:48 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;138;137;139;137;133;138;141;142;191;148 02:32:48 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;175;187;227;221;180;232;234;183;170;168 02:32:48 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;67;68;65;69;67;67;66;65;66 02:32:48 INFO - PROCESS | 1654 | __end_tp_report 02:32:48 INFO - PROCESS | 1654 | __start_cc_report 02:32:48 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1018 02:32:48 INFO - PROCESS | 1654 | __end_cc_report 02:32:48 INFO - PROCESS | 1654 | __startTimestamp1491557568519__endTimestamp 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | ------- Summary: start ------- 02:32:48 INFO - PROCESS | 1654 | Number of tests: 14 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:132.00 Median:140.50 stddev:26.60 (18.9%) stddev-sans-first:27.00 02:32:48 INFO - PROCESS | 1654 | Values: 110.0 121.0 99.0 174.0 178.0 132.0 119.0 119.0 119.0 149.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:130.50 stddev:17.58 (13.5%) stddev-sans-first:18.35 02:32:48 INFO - PROCESS | 1654 | Values: 137.0 114.0 112.0 146.0 114.0 116.0 113.0 124.0 161.0 144.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:209.50 Median:200.00 stddev:29.33 (14.7%) stddev-sans-first:30.33 02:32:48 INFO - PROCESS | 1654 | Values: 191.0 195.0 193.0 200.0 197.0 203.0 230.0 287.0 199.0 200.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:77.00 Median:84.50 stddev:11.88 (14.1%) stddev-sans-first:12.14 02:32:48 INFO - PROCESS | 1654 | Values: 86.0 65.0 83.0 88.0 65.0 66.0 74.0 87.0 94.0 62.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:76.00 stddev:12.76 (16.8%) stddev-sans-first:13.47 02:32:48 INFO - PROCESS | 1654 | Values: 92.0 86.0 110.0 103.0 105.0 104.0 66.0 90.0 101.0 97.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:102.40 Median:84.00 stddev:20.28 (24.1%) stddev-sans-first:20.83 02:32:48 INFO - PROCESS | 1654 | Values: 88.0 103.0 110.0 103.0 88.0 83.0 90.0 85.0 136.0 138.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:96.00 stddev:21.72 (22.6%) stddev-sans-first:22.47 02:32:48 INFO - PROCESS | 1654 | Values: 97.0 97.0 96.0 128.0 145.0 96.0 150.0 100.0 97.0 100.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:92.00 stddev:14.96 (16.3%) stddev-sans-first:15.65 02:32:48 INFO - PROCESS | 1654 | Values: 90.0 94.0 90.0 99.0 139.0 91.0 93.0 91.0 91.0 93.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:68.00 stddev:1.84 (2.7%) stddev-sans-first:1.86 02:32:48 INFO - PROCESS | 1654 | Values: 65.0 64.0 66.0 64.0 68.0 69.0 68.0 68.0 68.0 66.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:74.50 stddev:13.94 (18.7%) stddev-sans-first:14.61 02:32:48 INFO - PROCESS | 1654 | Values: 63.0 56.0 57.0 68.0 81.0 83.0 85.0 50.0 60.0 88.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:86.50 stddev:10.39 (12.0%) stddev-sans-first:10.94 02:32:48 INFO - PROCESS | 1654 | Values: 87.0 87.0 85.0 86.0 89.0 103.0 78.0 81.0 99.0 111.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:140.00 stddev:16.84 (12.0%) stddev-sans-first:17.70 02:32:48 INFO - PROCESS | 1654 | Values: 138.0 137.0 139.0 137.0 133.0 138.0 141.0 142.0 191.0 148.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.70 Median:204.00 stddev:27.29 (13.4%) stddev-sans-first:27.68 02:32:48 INFO - PROCESS | 1654 | Values: 175.0 187.0 227.0 221.0 180.0 232.0 234.0 183.0 170.0 168.0 02:32:48 INFO - PROCESS | 1654 | 02:32:48 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.32 (2.0%) stddev-sans-first:1.32 02:32:48 INFO - PROCESS | 1654 | Values: 68.0 67.0 68.0 65.0 69.0 67.0 67.0 66.0 65.0 66.0 02:32:48 INFO - PROCESS | 1654 | -------- Summary: end -------- 02:32:48 INFO - PROCESS | 1654 | 02:32:49 INFO - TEST-INFO | 1654: exit 0 02:32:49 INFO - TEST-OK | kraken | took 61846ms 02:32:49 INFO - SUITE-END | took 1179s 02:32:49 INFO - Completed test suite (00:19:39) 02:32:49 INFO - javascript benchmark 02:32:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7629.8386324682315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3453.7755045922013, 3518.57777066752, 3531.028967034066, 3540.3273378058166, 3587.497750314955, 6071.574989750719, 6086.239220097488, 6110.938890611093, 6164.815055548341, 6175.7529698811995, 6108.602940808844, 6111.786087486938, 6140.201773769415, 6195.31851496335, 6199.318075011745, 5501.559875209973, 5552.305961754781, 5566.666000039991, 5572.359178694442, 5612.130119831432, 5606.131049687305, 5622.184783206432, 5634.633748806335, 5636.210930469738, 5688.146777983315, 5416.133418653017, 5430.647007944491, 5578.9163162552595, 5624.887502249952, 5630.5495560355075, 5501.119820828648, 5526.723663816813, 5531.874490912642, 5536.252605898192, 5551.916721249185, 5484.972575137139, 5557.555395568385, 5569.498745112959, 5583.134614134816, 5674.347450043257, 6179.81460556184, 6197.411245931675, 6234.314225435199, 6253.843653908643, 6262.96868515659, 6220.751169953196, 6300.275468321156, 6446.871062578769, 6448.871022579569, 6450.838729031765, 6344.936550634527, 6409.0, 6424.967875160641, 6485.2541957675, 6505.7722979695955, 6266.467350275187, 6285.528585356116, 6291.622502649856, 6313.147725057104, 6336.746530138743, 6754.45964322853, 6790.42281406086, 6793.864122717518, 6799.0, 6812.1144251248015, 6261.937380626136, 6304.621722696653, 6310.1796766420075, 6314.17915670969, 6326.810195694136, 6539.08452816606, 6541.411272985454, 6551.508636852255, 6559.639219842955, 6562.278149403562, 6349.904751428732, 6387.457066149429, 6427.325130861286, 6429.196350456193, 6447.1296374989715, 6260.812175634738, 6299.716512756845, 6370.0, 6386.07401926718, 6445.774397896051, 5243.659162154448, 5253.212018197224, 5256.737163141827, 5262.657927234717, 5267.525922666979, 4616.330632058332, 4664.533546645308, 4671.7430541320555, 4680.789364478606, 4697.060587882437, 5953.166556682068, 5995.100734889801, 6094.969525152346, 6258.624482531062, 6287.276963149205, 6425.3574642536305, 6457.903131453032, 6529.836754081091, 6584.341565843377, 6597.076409302702, 6254.030625253093, 6278.434940855345, 6285.2457705075685, 6297.842553936097, 6332.303446620868, 5573.0, 5618.803341883015, 5707.857303567444, 5710.71446427677, 5812.0, 6423.4861211103935, 6623.006549017686, 6853.0405743195, 6854.485913556603, 6859.828504287433, 6271.027990661272, 6322.715477803602, 6412.166418365583, 6429.357064293533, 6453.677316134081, 6784.0, 6788.864222715617, 6809.795706128824, 6824.249332573314, 6829.726810927507, 6333.2716737575765, 6449.580777249652, 6463.806085817433, 6466.902996455151, 6483.967580162069, 5325.680459172462, 6225.688715564294, 6286.308506064238, 6353.904691429725, 6414.390632889868, 6214.161088253028, 6342.49260059185, 6350.587211831216, 6353.047042943595, 6368.235811702625, 5188.610854186027, 5192.324997750268, 6359.8092057238355, 6381.7128229228865, 6388.265349484869, 6116.204893363834, 6133.172021777003, 6146.661933593696, 6247.5939063960695, 6363.236411630634, 6294.779682711083, 6296.150019747459, 6336.683165841781, 6370.649614271074, 6374.58565193261, 6350.872982540416, 6351.872962540816, 6365.427111559982, 6370.394812492806, 6381.361863813581], "value": 6004.560444975403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.7490240574218, 822.3010441125044, 833.0, 862.8705694145877, 933.1927882381742, 38112.428313575285, 38828.0, 40524.79737601315, 40780.796096019556, 41018.97452563683, 32405.513917291202, 38802.417963730564, 39033.804830975874, 39246.0, 39321.41017884727, 7256.963715181417, 7449.180590135081, 7801.570913599764, 7940.602969851484, 7967.960160199191, 9555.713328600119, 10949.0, 11204.775904481905, 11222.102231821435, 11225.60710375137, 11186.161037922147, 11201.159913006557, 11219.214654974156, 11251.774964500704, 11267.323960562351, 10126.0, 10464.738381540446, 11176.161787865902, 11233.21367504279, 11236.66290011301, 10905.509252083595, 10917.508712107974, 10964.835527467094, 10974.506147223314, 10984.670459886216, 38868.61131388678, 39066.80466597677, 39134.60865391367, 39154.21691566153, 39201.60798392008, 3854.3062248795204, 3858.826352814102, 3885.825137868803, 3895.7272990890647, 3902.6097390260747, 27720.861395693093, 28234.43531129366, 28648.570271445944, 28951.131466056053, 29133.271668208254, 3105.6117985251844, 3116.812991220534, 3133.9373212535847, 3136.702013308732, 3229.11199420159, 345422.0, 346443.0, 346720.0, 347923.0, 349326.50673493446, 36947.815260923526, 44370.55629443664, 49658.0, 52943.14698985594, 52943.735281323345, 54747.083852064985, 55021.44978550163, 55037.72481137569, 55103.0, 55233.72383138059, 14947.177905215318, 15020.924895375672, 15060.698786024219, 15082.924585377223, 15085.3211605478, 12917.354132293347, 12927.870721292855, 12938.288394138224, 12969.935150324189, 12976.740465190644, 12579.93710031444, 12638.55765048219, 12696.0, 12731.427085781163, 12786.808197877039, 4871.34236878017, 5074.517920797518, 5451.100568406168, 5479.890402191933, 5484.945150548443, 957.3585697582637, 969.413504829568, 1011.1708399112657, 1068.4550879051585, 1155.9364234967159, 24696.876515617307, 26925.865370673022, 26983.0, 27060.729392706216, 27312.726872731415, 1044.300318786415, 1205.626255860686, 1250.5623031938749, 1259.6850787303174, 1300.7593595184921, 12652.304123273125, 13985.090969086978, 14020.878329733598, 14140.363683634261, 14168.716625667428, 456836.0, 457658.711706446, 457890.0, 458723.41276587476, 458848.0, 55359.723201383735, 55656.0, 55777.72111139418, 55850.72074639601, 55878.44121558814, 1404.2767974493145, 1452.186775405755, 1452.2375752729902, 1456.8106146201137, 1459.408939379553, 25600.671912651236, 25998.61002085008, 26281.474370512864, 26585.20244392671, 27473.0, 36359.45460818037, 45471.0, 45616.631501055024, 45628.54371456243, 45693.8576535576, 481008.0, 482901.0, 484561.0, 484820.0, 485761.0, 29326.41347173002, 29673.703262967094, 29814.701852981194, 30165.849170754005, 30861.691383085883, 37141.62858371382, 37992.240155197294, 39141.0, 40462.0, 40669.18661626692, 22406.66390004184, 27429.31426714348, 27852.86073569619, 28099.71900281053, 28658.57012144861, 27505.85076920841, 28350.858245708638, 28559.714402855705, 28565.28586785264, 28741.28146796263, 32288.192795180308, 32472.89289776541, 32475.188120296236, 32876.835615821765, 32962.340753184326, 28223.858880706288, 28423.147305580867, 28692.0, 28896.03429796423, 29156.56265155982, 23227.419314517272, 23236.883815580815, 23356.883215583814, 23579.646305305778, 24164.0], "value": 18510.71929217645, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.99264014719705, 405.355484779201, 406.5588836112818, 417.95193552741426, 445.4543184598867, 20307.08618112185, 21090.789092109073, 21264.36206913795, 21348.35954921351, 21691.891540542296, 20434.978251087425, 21195.894020529915, 21335.57328853422, 21491.57016859662, 22068.558628827413, 5661.320641523023, 6360.363963603637, 6609.702563384635, 6616.338366163369, 6651.235107962573, 5386.461353864602, 6314.494840412779, 7065.116860392451, 7384.778456646309, 7491.6254187290415, 7298.890516642228, 7471.327580517753, 7491.812704682372, 7508.587027713474, 7515.924840751577, 7257.927420725778, 7470.439717021218, 7525.924740752577, 7535.623218839063, 7538.962305188466, 7251.637418129125, 7356.639021780988, 7360.595167265798, 7370.77887663371, 7380.372668323199, 20781.792182078138, 24332.39169020771, 24591.87704061486, 25311.62032569513, 25673.0, 2860.313524754065, 2863.9284017899513, 2889.6821349651523, 2937.1922721251613, 2988.282812125096, 15240.314185861664, 15484.767728484081, 15523.534293971199, 15711.450099246471, 16049.679006419807, 2025.1798021801194, 2047.2732180076007, 2078.771335153132, 2082.916683332665, 2084.801943815335, 127836.72163278342, 157862.0, 161415.0, 168735.1563242176, 170274.0, 18321.0, 20620.484487887923, 21035.579288414145, 21135.154593816074, 21157.365279041653, 28571.85714071458, 30685.0, 30821.0, 31075.378492430023, 31079.0, 10008.249381296286, 10071.647492337734, 10218.591256349811, 10244.846327305097, 10394.324368915995, 6746.0, 7684.346830519356, 7842.764717058498, 8400.033996090528, 8439.831203375898, 7658.502197357266, 9307.72076837696, 9396.671116511026, 9436.528173591292, 9516.714498565054, 3656.8720094796554, 3678.319510890494, 3972.980135099306, 4040.7979601019833, 4213.052063285736, 799.2846402469758, 816.6284340625001, 873.3275377958936, 885.1900511032462, 915.3272344826547, 20602.793972060386, 21061.78938210629, 21138.048787804586, 21145.365639030853, 21214.46963825886, 772.8802035684478, 822.0258993093204, 907.7412937312835, 911.1753862754218, 944.7826999790087, 9572.186364158984, 10669.573217071385, 10742.946285268523, 10819.78360432787, 10832.78334433307, 533393.0, 682940.5852970703, 738105.0, 744153.0, 753353.0, 149294.0, 149878.0, 151003.2449837766, 151887.24056379648, 152230.0, 1129.164418330446, 1143.5825923537848, 1166.0787977497682, 1168.39243593331, 1172.7068232941765, 14605.0, 16766.832331676527, 17468.03925784043, 17590.29638814484, 18033.278668853098, 57975.71012144912, 64114.67942660257, 64803.0, 64896.67551662211, 65695.67152164209, 72650.54698906098, 89298.8745393908, 91724.54137729536, 91738.08261917296, 93565.0, 9548.37935534188, 10392.948035259775, 10975.176861735243, 11115.11079113685, 11174.329540227613, 8808.647654093764, 8817.955910220664, 9549.9045009549, 11298.322100673986, 13006.934965325112, 5896.764129434775, 11108.389038602954, 11115.666530004113, 11152.776944461228, 11391.430428478709, 8184.754457366289, 10188.439635820101, 10498.107660848607, 10506.317089389166, 10737.838932415863, 9284.396514226553, 10605.575776968835, 10766.169751142168, 10971.670849874517, 11386.373749443861, 9263.907360926305, 11512.42437878086, 11537.596184133516, 11624.593139240085, 11774.823377649513, 10673.092787113183, 11067.72330691739, 11096.223264371416, 11105.38920359388, 11176.497057632263], "value": 11179.08199975142, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1526.8702160316373, 1536.6696160325528, 1547.512533551931, 1552.6040859580805, 1580.1467207707835, 7906.723264685737, 8931.821363572724, 10979.451027448615, 10999.450027498624, 11161.38612376319, 10833.349999000066, 10942.069924056445, 10990.835137472925, 11010.119190464764, 11047.447627618627, 2346.4251258441664, 2369.9881500592473, 2381.0, 2382.201962342617, 2383.916562920298, 1958.4026871804165, 2276.169198242638, 2369.5853225685523, 2380.4048987753063, 2385.320183747632, 1960.5882764619448, 2289.1415719105335, 2331.0442718485424, 2350.74141844397, 2363.6809030780882, 1876.652819228447, 2335.521218150275, 2336.030547322859, 2348.7064116985375, 2349.776771206741, 2333.381653861728, 2336.8130549555995, 2337.1936681844904, 2337.9883100584557, 2348.377679914824, 10902.291351062155, 10906.291091079054, 10954.068904143158, 10958.726031849188, 10977.341359518454, 5452.863678408032, 5607.551395888319, 5614.242077319551, 5618.522425593788, 5624.268845050158, 3122.172624254574, 3209.8555564999633, 3307.140143562667, 3364.7981121132566, 3396.7961922284744, 1081.2485322700727, 1086.4513420722565, 1119.9328040317607, 1122.5397586989295, 1136.2443974756777, 10889.891101089046, 13414.195148291134, 14794.482193123189, 14863.182524961123, 14955.055842740374, 3903.921921561553, 3952.4861767970556, 4323.978380108079, 4489.820407183742, 4599.954000460019, 11491.310521368745, 11588.246763960316, 11617.53529858813, 11627.88372116268, 11628.069754419796, 5540.0, 5601.7759289628775, 5603.327600687943, 5605.915911261334, 5621.353544342372, 4062.4921884764403, 4891.29076283944, 5089.770960306795, 5092.0, 5103.515166059218, 3675.61406052366, 3769.5853456119803, 3791.7724936503896, 3837.175007373428, 3873.283442563135, 989.0702739424918, 990.7473594233425, 996.6262651505685, 997.6159178716247, 999.6801023672355, 913.4656226107669, 919.1773362840221, 937.7843096087938, 945.8864936207699, 972.9173020293213, 6375.840603984845, 6441.420272175526, 6594.109795177686, 6610.669466526654, 6678.165229346332, 867.544539116956, 994.2096033653263, 1001.0790073132736, 1055.8257887448485, 1058.941058941059, 4129.566395528486, 4228.154369126187, 4233.745975241495, 4350.042990542076, 4380.211561918885, 10652.414117223472, 11175.44122793857, 13086.411111500005, 13515.391807368693, 13572.864271357357, 12737.490500379881, 13506.05457617977, 13506.864931350561, 13523.39144738489, 13525.458981640624, 9970.40177589347, 10192.082712555906, 10247.026532479402, 10264.743381415525, 10313.12338451225, 6655.667216639052, 6718.563293385914, 6730.764423245163, 6832.214295355899, 6888.758893438706, 9699.272554558578, 11813.881861181279, 11996.820047699466, 12141.514339426323, 12157.75684486323, 11010.39442830652, 11297.152713546353, 12200.877991219973, 12321.0, 12398.752024959269, 9877.06167914047, 10233.590656373663, 10347.482625868526, 10354.637587684696, 10395.896041039492, 10004.29969902129, 10155.035271649182, 10185.643502477378, 10242.692719218436, 10302.690919272434, 8665.306775457822, 8669.349798764992, 8702.521361325187, 8772.166644168794, 8933.508657023925, 7353.926460735324, 8369.95815020921, 8481.15188481147, 8590.78523036929, 8609.354298427517, 7769.92230077692, 7780.105287891738, 7791.92208077912, 7822.256885595859, 7856.450048496779, 5734.910367030251, 5959.255093113361, 5995.91006134917, 6024.0, 6066.939330606814, 5937.762489500372, 5940.970295148497, 5961.880762384815, 5973.49225315853, 6076.361981991828], "value": 4998.762102543305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.40268308994536, 355.63369729178953, 381.96297053499745, 391.3269177015534, 396.0731887383522, 4535.160995215883, 5054.292399064129, 5207.895842083156, 5357.107162132444, 5390.299261096062, 4318.978405107978, 5160.91620759641, 5294.523492885649, 5373.623846330758, 5423.322084739408, 4158.189153115129, 4230.9788451057775, 4261.254280500915, 4412.5587441255775, 4447.35513350564, 3433.1588760753525, 4286.992556749162, 4322.632576231024, 4371.431713877191, 4437.7352454550455, 4163.646090082347, 4251.744895306276, 4389.561043895601, 4406.69153159279, 4464.174127786354, 4051.7568945863195, 4124.463819703435, 4351.282038463682, 4363.912721745547, 4388.990532177617, 3878.4376265441633, 4036.233115708006, 4036.9192616147807, 4083.3874918762126, 4089.078133277355, 5154.742262886841, 5198.322863725184, 5220.843374698765, 5228.9345708445335, 5231.869203269911, 3902.570717221103, 4345.067079721176, 5001.774920128603, 5064.341635587387, 5096.572959571326, 4889.921783242108, 5507.5593952484105, 5706.86511999602, 5760.452756988079, 5826.833813823095, 3822.4648549203134, 4357.542458041891, 4497.707649002804, 4536.591706746376, 4610.631149508032, 5185.562785490813, 5777.959967205901, 5954.868574970785, 6024.126501657279, 6041.607295525777, 4200.0, 4352.303631419021, 4517.628245171246, 4541.704789188692, 4581.473130589959, 3980.601939805996, 4639.628829693616, 4676.836310729108, 4695.319178719066, 4734.26618874075, 4557.316402539645, 4740.293494342034, 4856.2958371036, 4981.601471882241, 5012.548870601663, 5370.087085195526, 5458.072127738294, 5643.52030077448, 5701.4298570142655, 5747.655140691572, 1365.1399618240644, 1416.4489883577671, 1428.8571142885628, 1462.6050966239056, 1589.348367169455, 1025.34406932803, 1038.1383451735041, 1139.9259048161844, 1197.2935967779051, 1225.9570915017932, 4388.758618276025, 4400.647948164139, 4684.484706682262, 5034.622403319766, 5230.63385563007, 5195.870103247449, 5229.843104706865, 5275.050490911673, 5802.564807639444, 5842.912356314659, 4874.707517548958, 5019.644695932078, 5183.948160518422, 5261.368635763656, 5363.946360536423, 2559.9104031359184, 2757.9724202758116, 2834.0, 2942.911712648624, 3006.969930300713, 5843.415658434123, 5970.820875373659, 5971.0744834550715, 6022.964613708826, 6124.969375153096, 5137.255098010767, 5357.919631205535, 5584.609077364546, 5636.549076073904, 5894.616849904828, 4776.8328108516735, 5193.454687257935, 5504.339479262514, 5816.941830581809, 6098.969505152446, 5062.519060689228, 5320.920186197288, 5921.200637913998, 5925.229720136527, 5954.672493012926, 4165.416841642112, 5554.028869358428, 5783.826485205451, 5801.607614172653, 5929.983805506148, 4847.975760121177, 5672.007398705127, 6000.609960352563, 6124.539635106151, 6159.661218632839, 4366.934495982627, 4648.349231107715, 4696.248600224085, 4700.247960326331, 4717.386739723814, 4719.669623126486, 5675.659460432387, 5773.1051686988585, 5861.9706901465215, 5906.751286792333, 4775.42694876617, 4901.573721008123, 5377.193420986883, 5503.798480607789, 5561.833145005657, 5299.198272587339, 5391.649542779707, 5667.838093190969, 5728.942710572841, 5779.68211748341, 4992.700437973734, 5390.014236830837, 5491.725413729378, 5566.721663916707, 5617.578681598815, 5263.815516560738, 5540.279763630702, 5711.657300561979, 5716.399778023248, 5752.453516915886, 5171.612129090408, 5238.449962753856, 5514.338279406497, 5603.0, 5612.775488980558], "value": 4289.834564829927, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.9898001529976, 711.8540699156673, 789.9605019749015, 815.9755207343782, 837.7486753973808, 13007.544735934243, 16076.437324693637, 16140.95083819551, 16320.918395408036, 16374.345026198953, 15104.960830287908, 15311.540653780405, 15318.387264509407, 15326.080435173897, 15339.539813805604, 914.7210100919234, 923.9630414783434, 931.776373670321, 939.1922946266205, 947.9905200947971, 933.6032186320821, 934.0, 936.0779632062413, 937.1986951156765, 937.4890684576887, 971.621067783565, 990.7374545745383, 992.0377234082928, 992.3500107429645, 993.9006099390075, 989.45579931038, 989.5645915797027, 989.8713167288242, 991.7818080022383, 993.3096497933939, 720.121451828768, 732.721565804996, 733.069002366991, 734.5519233267703, 734.867723809714, 16140.273687684083, 16393.34426622934, 16425.342986280535, 16472.341106355732, 16577.91711041449, 13684.178949263076, 13805.240711760849, 13995.65010874726, 14001.0, 14167.291635418187, 11508.827367589492, 11831.645050648494, 11844.407779610985, 11868.881311186864, 11976.70058248542, 3591.0, 3627.818609069536, 3642.508261384732, 3724.0131365188245, 3820.0449887528116, 52213.73893130548, 73399.0, 96947.51526242394, 97141.51429242949, 105472.47263763708, 8951.73144805657, 10736.677899663022, 10920.726981825359, 10943.35849622547, 10988.340699558052, 17551.824481755273, 20967.475813104793, 21072.367828965154, 21284.467888302916, 21462.785372146078, 7895.131535531087, 7917.643706033243, 7927.722529711433, 8035.7991050223045, 8169.9591502043295, 4329.0, 4387.956120438818, 4413.426254586947, 4437.556244375602, 4451.799669014902, 3338.4157772389735, 3389.237421580174, 3405.7615966882577, 3420.931581368359, 3439.673231043047, 612.2836281550597, 615.972281247345, 618.6597371445687, 620.0667994667996, 625.1560393468781, 658.1969996604136, 675.6148995072859, 676.2189670930084, 686.1457485430572, 693.6427739714052, 10188.949055254676, 10206.921226818335, 10261.897381026094, 10559.208059395576, 10662.466876656292, 629.9685015749194, 660.7059858362983, 684.3567046975926, 684.4114061906756, 684.6302996381899, 3945.112349721347, 4710.952890471051, 4988.850334489971, 5002.824901128443, 5004.774785134678, 93472.0, 96424.0, 96855.0, 97534.0, 98665.0, 58975.0, 74856.0, 81557.59221203974, 84117.0, 85765.57117214374, 60702.99999999912, 75806.48387032228, 76373.6181319101, 77199.0, 78070.6096469514, 10690.839637405601, 10929.836052459379, 11051.301115241637, 11129.888701113208, 11164.553417863195, 47147.764261179844, 47491.76254118846, 47726.76136619433, 48460.03079938452, 48736.53790386294, 61848.0, 66466.66766666136, 69227.0, 70170.29829701637, 72361.91457128253, 12492.0, 12658.809658225216, 12781.16922400041, 12782.808257876326, 12971.805422918853, 14483.637909052359, 14582.823097800128, 14630.0, 14802.777958330418, 14906.105633662015, 11292.49183786748, 11913.880861191625, 13312.0, 13765.849661773995, 13852.722945541234, 9908.900910990798, 11812.3503207321, 12003.81994270104, 12012.0, 12171.75656486883, 11319.377434241196, 11717.707057323634, 12095.818562721743, 12332.938335308625, 12454.81317780216, 8890.422122562013, 8894.777630559029, 9004.864927025968, 9125.17873391398, 9279.747234123577, 8291.549589443823, 9992.750181245527, 10167.44079075658, 10310.896891030994, 10339.327943683636], "value": 7403.525280124576, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 121.0, 99.0, 174.0, 178.0, 132.0, 119.0, 119.0, 119.0, 149.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 114.0, 112.0, 146.0, 114.0, 116.0, 113.0, 124.0, 161.0, 144.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 195.0, 193.0, 200.0, 197.0, 203.0, 230.0, 287.0, 199.0, 200.0], "value": 209.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 65.0, 83.0, 88.0, 65.0, 66.0, 74.0, 87.0, 94.0, 62.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 86.0, 110.0, 103.0, 105.0, 104.0, 66.0, 90.0, 101.0, 97.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 103.0, 110.0, 103.0, 88.0, 83.0, 90.0, 85.0, 136.0, 138.0], "value": 102.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 128.0, 145.0, 96.0, 150.0, 100.0, 97.0, 100.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 90.0, 99.0, 139.0, 91.0, 93.0, 91.0, 91.0, 93.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 64.0, 68.0, 69.0, 68.0, 68.0, 68.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 56.0, 57.0, 68.0, 81.0, 83.0, 85.0, 50.0, 60.0, 88.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 86.0, 89.0, 103.0, 78.0, 81.0, 99.0, 111.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 139.0, 137.0, 133.0, 138.0, 141.0, 142.0, 191.0, 148.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 187.0, 227.0, 221.0, 180.0, 232.0, 234.0, 183.0, 170.0, 168.0], "value": 197.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 65.0, 69.0, 67.0, 67.0, 66.0, 65.0, 66.0], "value": 66.8, "unit": "score"}]}]} 02:32:49 INFO - Return code: 0 02:32:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:32:49 ERROR - # TBPL SUCCESS # 02:32:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:32:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:32:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:32:49 INFO - 2017-04-07 02:32:49.854 screenresolution[1659:10871] starting screenresolution argv=screenresolution get 02:32:49 INFO - 2017-04-07 02:32:49.857 screenresolution[1659:10871] Display 0: 1600x1200x32@60 02:32:49 INFO - 2017-04-07 02:32:49.863 screenresolution[1660:10874] starting screenresolution argv=screenresolution list 02:32:49 INFO - Available Modes on Display 0 02:32:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:32:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:32:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:32:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:32:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:32:50 INFO - Intel Iris: 02:32:50 INFO - Chipset Model: Intel Iris 02:32:50 INFO - Type: GPU 02:32:50 INFO - Bus: Built-In 02:32:50 INFO - VRAM (Dynamic, Max): 1536 MB 02:32:50 INFO - Vendor: Intel (0x8086) 02:32:50 INFO - Device ID: 0x0a2e 02:32:50 INFO - Revision ID: 0x0009 02:32:50 INFO - Displays: 02:32:50 INFO - Display: 02:32:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:32:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:32:50 INFO - Display Serial Number: 1600x1200 60 02:32:50 INFO - Main Display: Yes 02:32:50 INFO - Mirror: Off 02:32:50 INFO - Online: Yes 02:32:50 INFO - Rotation: Supported 02:32:50 INFO - Return code: 0 02:32:50 INFO - Running post-action listener: _resource_record_post_action 02:32:50 INFO - [mozharness: 2017-04-07 09:32:50.098074Z] Finished run-tests step (success) 02:32:50 INFO - Running post-run listener: _resource_record_post_run 02:32:50 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 40511488; Write bytes: 513676288; Read time: 390; Write time: 1908 02:32:50 INFO - TinderboxPrint: CPU usage
54.7% 02:32:50 INFO - TinderboxPrint: I/O read bytes / time
40,511,488 / 390 02:32:50 INFO - TinderboxPrint: I/O write bytes / time
513,676,288 / 1,908 02:32:50 INFO - TinderboxPrint: CPU idle
2,177.3 (45.3%) 02:32:50 INFO - TinderboxPrint: CPU system
968.4 (20.2%) 02:32:50 INFO - TinderboxPrint: CPU user
1,656.1 (34.5%) 02:32:50 INFO - TinderboxPrint: Swap in / out
249,147,392 / 0 02:32:50 INFO - install - Wall time: 20s; CPU: 55.0%; Read bytes: 211196416; Write bytes: 199344128; Read time: 15825; Write time: 406 02:32:50 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 33103872; Write bytes: 303268864; Read time: 300; Write time: 1467 02:32:50 INFO - Running post-run listener: _upload_blobber_files 02:32:50 INFO - Blob upload gear active. 02:32:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:32:50 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:32:50 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:32:50 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:32:51 INFO - (blobuploader) - INFO - Open directory for files ... 02:32:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:32:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:32:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:32:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:32:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:32:52 INFO - (blobuploader) - INFO - Done attempting. 02:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:32:53 INFO - (blobuploader) - INFO - Done attempting. 02:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:32:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:32:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:32:54 INFO - (blobuploader) - INFO - Done attempting. 02:32:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:32:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:32:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:32:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:32:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:32:56 INFO - (blobuploader) - INFO - Done attempting. 02:32:56 INFO - (blobuploader) - INFO - Iteration through files over. 02:32:56 INFO - Return code: 0 02:32:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:32:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:32:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a95190cfea685a1023cb3a55e3a20e2b41b4b95aef6c7ac3d3e679574332232a8561819ad35f5b21131cb82176d30744c632fed58c298afcc8979a4a91b73c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d95cf2c0a72867f490f529577ef7c2b54df5a85b2f364f13e9340b3d37ffdec3f441785d24b418de19031b742488358bfde5cc67c96e6a75377d20e6dfaf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38b56e15b7e7979195331ced5f245b614f3f2a5b16a4434b8f90b2c570da3a9e074b68d3f382d53812e832950ad45842f71097e921d245a583c360b758e0ca8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6a4713e3e713477636380fba820abdcb44d6b6df11aafbcc37ff6d21c8f1d1d5cda7c00b0f1aeb27876b10f878766883a8227095744801f0c1133bbf6e34f1d"} 02:32:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:32:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:32:56 INFO - Contents: 02:32:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a95190cfea685a1023cb3a55e3a20e2b41b4b95aef6c7ac3d3e679574332232a8561819ad35f5b21131cb82176d30744c632fed58c298afcc8979a4a91b73c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d95cf2c0a72867f490f529577ef7c2b54df5a85b2f364f13e9340b3d37ffdec3f441785d24b418de19031b742488358bfde5cc67c96e6a75377d20e6dfaf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38b56e15b7e7979195331ced5f245b614f3f2a5b16a4434b8f90b2c570da3a9e074b68d3f382d53812e832950ad45842f71097e921d245a583c360b758e0ca8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6a4713e3e713477636380fba820abdcb44d6b6df11aafbcc37ff6d21c8f1d1d5cda7c00b0f1aeb27876b10f878766883a8227095744801f0c1133bbf6e34f1d"} 02:32:56 INFO - Running post-run listener: copy_logs_to_upload_dir 02:32:56 INFO - Copying logs to upload dir... 02:32:56 INFO - mkdir: /builds/slave/test/build/upload/logs 02:32:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.661539 ========= master_lag: 1.44 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2017-04-07 02:32:57.457830) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 02:32:57.460990) ========= 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.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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/31a95190cfea685a1023cb3a55e3a20e2b41b4b95aef6c7ac3d3e679574332232a8561819ad35f5b21131cb82176d30744c632fed58c298afcc8979a4a91b73c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d95cf2c0a72867f490f529577ef7c2b54df5a85b2f364f13e9340b3d37ffdec3f441785d24b418de19031b742488358bfde5cc67c96e6a75377d20e6dfaf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38b56e15b7e7979195331ced5f245b614f3f2a5b16a4434b8f90b2c570da3a9e074b68d3f382d53812e832950ad45842f71097e921d245a583c360b758e0ca8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6a4713e3e713477636380fba820abdcb44d6b6df11aafbcc37ff6d21c8f1d1d5cda7c00b0f1aeb27876b10f878766883a8227095744801f0c1133bbf6e34f1d"} build_url:https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011142 build_url: 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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/31a95190cfea685a1023cb3a55e3a20e2b41b4b95aef6c7ac3d3e679574332232a8561819ad35f5b21131cb82176d30744c632fed58c298afcc8979a4a91b73c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/18d95cf2c0a72867f490f529577ef7c2b54df5a85b2f364f13e9340b3d37ffdec3f441785d24b418de19031b742488358bfde5cc67c96e6a75377d20e6dfaf8d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/38b56e15b7e7979195331ced5f245b614f3f2a5b16a4434b8f90b2c570da3a9e074b68d3f382d53812e832950ad45842f71097e921d245a583c360b758e0ca8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6a4713e3e713477636380fba820abdcb44d6b6df11aafbcc37ff6d21c8f1d1d5cda7c00b0f1aeb27876b10f878766883a8227095744801f0c1133bbf6e34f1d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 02:32:57.501979) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:32:57.502316) ========= 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.hiLT4EZHAo/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.7XeHIYv61S/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.005091 ========= master_lag: 0.25 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:32:57.754848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 02:32:57.755159) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 02:32:57.755862) ========= ========= Total master_lag: 2.57 =========