builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0142 starttime: 1491481097.33 results: success (0) buildid: 20170406041033 builduid: 344f39edffbd4593a2c59b1630a91527 revision: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:17.330940) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:17.331440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:17.331731) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:17.435466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:17.537861) ========= 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.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-06 05:18:18-- 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% 9.54M=0.001s 2017-04-06 05:18:18 (9.54 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101810 ========= master_lag: 0.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:18.041883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:18.042237) ========= 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.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.038400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:18.102292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:18:18.102669) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a --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 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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-06 05:18:18,288 truncating revision to first 12 chars 2017-04-06 05:18:18,288 Setting DEBUG logging. 2017-04-06 05:18:18,288 attempt 1/10 2017-04-06 05:18:18,288 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9d164f1dc3c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 05:18:18,665 unpacking tar archive at: mozilla-aurora-9d164f1dc3c2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645817 ========= master_lag: 0.68 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 05:18:19.428567) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:19.428953) ========= script_repo_revision: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:19.429355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:19.429625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 05:18:19.449900) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 05:18:19.450317) ========= /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.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:18:19 INFO - MultiFileLogger online at 20170406 05:18:19 in /builds/slave/test 05:18:19 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 05:18:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:19 INFO - {'append_to_log': False, 05:18:19 INFO - 'base_work_dir': '/builds/slave/test', 05:18:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:18:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:19 INFO - 'branch': 'Mozilla-Aurora', 05:18:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:19 INFO - 'config_files': ('talos/mac_config.py',), 05:18:19 INFO - 'default_actions': ('clobber', 05:18:19 INFO - 'read-buildbot-config', 05:18:19 INFO - 'download-and-extract', 05:18:19 INFO - 'populate-webroot', 05:18:19 INFO - 'create-virtualenv', 05:18:19 INFO - 'install', 05:18:19 INFO - 'run-tests'), 05:18:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:19 INFO - 'download_minidump_stackwalk': True, 05:18:19 INFO - 'download_symbols': 'ondemand', 05:18:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:19 INFO - 'gecko_profile': False, 05:18:19 INFO - 'gecko_profile_interval': 0, 05:18:19 INFO - 'installer_path': 'installer.exe', 05:18:19 INFO - 'log_level': 'info', 05:18:19 INFO - 'log_name': 'talos', 05:18:19 INFO - 'log_to_console': True, 05:18:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:19 INFO - 'opt_config_files': (), 05:18:19 INFO - 'pip_index': False, 05:18:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:19 INFO - 'cmd': ('bash', 05:18:19 INFO - '-c', 05:18:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:19 INFO - 'enabled': True, 05:18:19 INFO - 'halt_on_failure': False, 05:18:19 INFO - 'name': 'check_screen_resolution'},), 05:18:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:19 INFO - 'cmd': ('bash', 05:18:19 INFO - '-c', 05:18:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:19 INFO - 'enabled': True, 05:18:19 INFO - 'halt_on_failure': False, 05:18:19 INFO - 'name': 'check_screen_resolution'},), 05:18:19 INFO - 'run_cmd_checks_enabled': True, 05:18:19 INFO - 'suite': 'dromaeojs-e10s', 05:18:19 INFO - 'system_bits': '32', 05:18:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:19 INFO - 'title': 't-yosemite-r7-0142', 05:18:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:19 INFO - 'use_talos_json': True, 05:18:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:19 INFO - 'work_dir': 'build'} 05:18:19 INFO - [mozharness: 2017-04-06 12:18:19.748213Z] Running clobber step. 05:18:19 INFO - Running pre-action listener: _resource_record_pre_action 05:18:19 INFO - Running main action method: clobber 05:18:19 INFO - rmtree: /builds/slave/test/build 05:18:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:22 INFO - Running post-action listener: _resource_record_post_action 05:18:22 INFO - [mozharness: 2017-04-06 12:18:22.231921Z] Finished clobber step (success) 05:18:22 INFO - [mozharness: 2017-04-06 12:18:22.232096Z] Running read-buildbot-config step. 05:18:22 INFO - Running pre-action listener: _resource_record_pre_action 05:18:22 INFO - Running main action method: read_buildbot_config 05:18:22 INFO - Using buildbot properties: 05:18:22 INFO - { 05:18:22 INFO - "project": "", 05:18:22 INFO - "product": "firefox", 05:18:22 INFO - "script_repo_revision": "production", 05:18:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:18:22 INFO - "repository": "", 05:18:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:18:22 INFO - "buildid": "20170406041033", 05:18:22 INFO - "pgo_build": "False", 05:18:22 INFO - "basedir": "/builds/slave/test", 05:18:22 INFO - "buildnumber": 10, 05:18:22 INFO - "slavename": "t-yosemite-r7-0142", 05:18:22 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:18:22 INFO - "platform": "yosemite_r7", 05:18:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:18:22 INFO - "branch": "mozilla-aurora", 05:18:22 INFO - "repo_path": "releases/mozilla-aurora", 05:18:22 INFO - "revision": "9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a", 05:18:22 INFO - "stage_platform": "macosx64", 05:18:22 INFO - "builduid": "344f39edffbd4593a2c59b1630a91527", 05:18:22 INFO - "slavebuilddir": "test" 05:18:22 INFO - } 05:18:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 05:18:22 INFO - Running post-action listener: _resource_record_post_action 05:18:22 INFO - [mozharness: 2017-04-06 12:18:22.235683Z] Finished read-buildbot-config step (success) 05:18:22 INFO - [mozharness: 2017-04-06 12:18:22.235812Z] Running download-and-extract step. 05:18:22 INFO - Running pre-action listener: _resource_record_pre_action 05:18:22 INFO - Running main action method: download_and_extract 05:18:22 INFO - mkdir: /builds/slave/test/build/tests 05:18: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')]} 05:18:22 INFO - trying https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 05:18:22 INFO - Downloading https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/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 05:18:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/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 05:18:23 INFO - Downloaded 1199 bytes. 05:18:23 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 05:18:23 INFO - Using the following test package requirements: 05:18:23 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 05:18:23 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 05:18:23 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 05:18:23 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'jsshell-mac.zip'], 05:18:23 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 05:18:23 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 05:18:23 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 05:18:23 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 05:18:23 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 05:18:23 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 05:18:23 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 05:18:23 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:18:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 05:18:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:23 INFO - Fetch https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 05:18:25 INFO - Expected file size: 51790431 05:18:25 INFO - Obtained file size: 51790431 05:18:41 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 05:18:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 05:18:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:41 INFO - Fetch https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 05:18:42 INFO - Expected file size: 51790431 05:18:42 INFO - Obtained file size: 51790431 05:18:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 05:18:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:18:57 INFO - Fetch https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 05:18:59 INFO - Expected file size: 14404438 05:18:59 INFO - Obtained file size: 14404438 05:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:18:59 INFO - trying https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:18:59 INFO - Downloading https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 05:18:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/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 05:19:02 INFO - Downloaded 74686966 bytes. 05:19:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:19:02 INFO - mkdir: /builds/slave/test/properties 05:19:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:19:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:19:02 INFO - Contents: 05:19:02 INFO - build_url:https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 05:19:02 INFO - Running post-action listener: _resource_record_post_action 05:19:02 INFO - Running post-action listener: set_extra_try_arguments 05:19:02 INFO - [mozharness: 2017-04-06 12:19:02.914397Z] Finished download-and-extract step (success) 05:19:02 INFO - [mozharness: 2017-04-06 12:19:02.914516Z] Running populate-webroot step. 05:19:02 INFO - Running pre-action listener: _resource_record_pre_action 05:19:02 INFO - Running main action method: populate_webroot 05:19:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:19:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:19:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:19:02 INFO - '--tppagecycles', 05:19:02 INFO - '7'], 05:19:02 INFO - 'tests': ['tsvgm']}}, 05:19:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:19:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:19:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:19:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:19:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['damp', 'tps']}, 05:19:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'tests': ['damp', 'tps']}, 05:19:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['dromaeo_dom']}, 05:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:19:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['basic_compositor_video']}, 05:19:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:19:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['a11yr', 05:19:02 INFO - 'ts_paint', 05:19:02 INFO - 'tpaint', 05:19:02 INFO - 'sessionrestore', 05:19:02 INFO - 'sessionrestore_no_auto_restore', 05:19:02 INFO - 'tabpaint']}, 05:19:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tsvgx', 05:19:02 INFO - 'tsvgr_opacity', 05:19:02 INFO - 'tart', 05:19:02 INFO - 'tscrollx', 05:19:02 INFO - 'cart', 05:19:02 INFO - 'tsvg_static']}, 05:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:19:02 INFO - 'tsvgr_opacity', 05:19:02 INFO - 'tart', 05:19:02 INFO - 'tscrollx', 05:19:02 INFO - 'cart', 05:19:02 INFO - 'tsvg_static']}, 05:19:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s'], 05:19:02 INFO - 'tests': ['tp5o']}, 05:19:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:19:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'talos_options': ['--disable-e10s', 05:19:02 INFO - '--xperf_path', 05:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:02 INFO - 'tests': ['tp5n']}, 05:19:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:19:02 INFO - 'talos_options': ['--xperf_path', 05:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:19:02 INFO - 'tests': ['tp5n']}}, 05:19:02 INFO - 'talos.zip': {'path': '', 05:19:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:19:02 INFO - Running post-action listener: _resource_record_post_action 05:19:02 INFO - [mozharness: 2017-04-06 12:19:02.932730Z] Finished populate-webroot step (success) 05:19:02 INFO - [mozharness: 2017-04-06 12:19:02.932843Z] Running create-virtualenv step. 05:19:02 INFO - Running pre-action listener: _resource_record_pre_action 05:19:02 INFO - Running main action method: create_virtualenv 05:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:19:02 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 05:19:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:19:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:19:03 INFO - Using real prefix '/tools/python27' 05:19:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:19:03 INFO - Installing distribute.............................................................................................................................................................................................done. 05:19:06 INFO - Installing pip.................done. 05:19:06 INFO - Return code: 0 05:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:19:06 INFO - Reading from file tmpfile_stdout 05:19:06 INFO - Output received: 05:19:06 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) 05:19:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:19:06 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')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:06 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 05:19:06 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 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:07 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 05:19:07 INFO - Cleaning up... 05:19:07 INFO - Return code: 0 05:19:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:07 INFO - 'HOME': '/Users/cltbld', 05:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:07 INFO - 'LOGNAME': 'cltbld', 05:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:07 INFO - 'MOZ_NO_REMOTE': '1', 05:19:07 INFO - 'NO_EM_RESTART': '1', 05:19:07 INFO - 'PAGER': '/bin/cat', 05:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:07 INFO - 'PWD': '/builds/slave/test', 05:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:07 INFO - 'SHELL': '/bin/bash', 05:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:07 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:07 INFO - 'USER': 'cltbld', 05:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:07 INFO - 'XPC_FLAGS': '0x0', 05:19:07 INFO - 'XPC_SERVICE_NAME': '0', 05:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:07 INFO - Downloading/unpacking psutil>=3.1.1 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:11 INFO - Installing collected packages: psutil 05:19:11 INFO - Running setup.py install for psutil 05:19:11 INFO - building 'psutil._psutil_osx' extension 05:19:11 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 05:19:11 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 05:19:11 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 05:19:11 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 05:19:11 INFO - building 'psutil._psutil_posix' extension 05:19:11 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 05:19:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:19:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:19:12 INFO - ^ 05:19:12 INFO - 1 warning generated. 05:19:12 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 05:19:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:12 INFO - Successfully installed psutil 05:19:12 INFO - Cleaning up... 05:19:12 INFO - Return code: 0 05:19:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:19: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')]} 05:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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')]} 05:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:19: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 mozsystemmonitor==0.3 05:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:13 INFO - 'HOME': '/Users/cltbld', 05:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:13 INFO - 'LOGNAME': 'cltbld', 05:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:13 INFO - 'MOZ_NO_REMOTE': '1', 05:19:13 INFO - 'NO_EM_RESTART': '1', 05:19:13 INFO - 'PAGER': '/bin/cat', 05:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:13 INFO - 'PWD': '/builds/slave/test', 05:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:13 INFO - 'SHELL': '/bin/bash', 05:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:13 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:13 INFO - 'USER': 'cltbld', 05:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:13 INFO - 'XPC_FLAGS': '0x0', 05:19:13 INFO - 'XPC_SERVICE_NAME': '0', 05:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:19:16 INFO - Installing collected packages: mozsystemmonitor 05:19:16 INFO - Running setup.py install for mozsystemmonitor 05:19:16 INFO - Successfully installed mozsystemmonitor 05:19:16 INFO - Cleaning up... 05:19:16 INFO - Return code: 0 05:19:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:16 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:16 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 05:19:16 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 05:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:16 INFO - 'HOME': '/Users/cltbld', 05:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:16 INFO - 'LOGNAME': 'cltbld', 05:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:16 INFO - 'MOZ_NO_REMOTE': '1', 05:19:16 INFO - 'NO_EM_RESTART': '1', 05:19:16 INFO - 'PAGER': '/bin/cat', 05:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:16 INFO - 'PWD': '/builds/slave/test', 05:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:16 INFO - 'SHELL': '/bin/bash', 05:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:16 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:16 INFO - 'USER': 'cltbld', 05:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:16 INFO - 'XPC_FLAGS': '0x0', 05:19:16 INFO - 'XPC_SERVICE_NAME': '0', 05:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:16 INFO - Downloading/unpacking jsonschema==2.5.1 05:19:16 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 05:19:16 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 05:19:16 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 05:19:16 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 05:19:16 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 05:19:16 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 05:19:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:19:19 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 05:19:19 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 05:19:19 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 05:19:19 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 05:19:19 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 05:19:19 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 05:19:19 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:20 INFO - warning: no files found matching '*.txt' 05:19:20 INFO - no previously-included directories found matching 'build' 05:19:20 INFO - no previously-included directories found matching 'dist' 05:19:20 INFO - no previously-included directories found matching '.git*' 05:19:20 INFO - Installing collected packages: jsonschema, functools32 05:19:20 INFO - Running setup.py install for functools32 05:19:20 INFO - warning: no files found matching '*.txt' 05:19:20 INFO - no previously-included directories found matching 'build' 05:19:20 INFO - no previously-included directories found matching 'dist' 05:19:20 INFO - no previously-included directories found matching '.git*' 05:19:20 INFO - Successfully installed jsonschema functools32 05:19:20 INFO - Cleaning up... 05:19:20 INFO - Return code: 0 05:19:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:20 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:20 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 05:19:20 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 05:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:20 INFO - 'HOME': '/Users/cltbld', 05:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:20 INFO - 'LOGNAME': 'cltbld', 05:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:20 INFO - 'MOZ_NO_REMOTE': '1', 05:19:20 INFO - 'NO_EM_RESTART': '1', 05:19:20 INFO - 'PAGER': '/bin/cat', 05:19:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:20 INFO - 'PWD': '/builds/slave/test', 05:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:20 INFO - 'SHELL': '/bin/bash', 05:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:20 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:20 INFO - 'USER': 'cltbld', 05:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:20 INFO - 'XPC_FLAGS': '0x0', 05:19:20 INFO - 'XPC_SERVICE_NAME': '0', 05:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:19:20 INFO - Cleaning up... 05:19:20 INFO - Return code: 0 05:19:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:20 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:20 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 05:19:20 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 05:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:20 INFO - 'HOME': '/Users/cltbld', 05:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:20 INFO - 'LOGNAME': 'cltbld', 05:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:20 INFO - 'MOZ_NO_REMOTE': '1', 05:19:20 INFO - 'NO_EM_RESTART': '1', 05:19:20 INFO - 'PAGER': '/bin/cat', 05:19:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:20 INFO - 'PWD': '/builds/slave/test', 05:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:20 INFO - 'SHELL': '/bin/bash', 05:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:20 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:20 INFO - 'USER': 'cltbld', 05:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:20 INFO - 'XPC_FLAGS': '0x0', 05:19:20 INFO - 'XPC_SERVICE_NAME': '0', 05:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:19:20 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 05:19:20 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 05:19:20 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 05:19:20 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 05:19:20 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 05:19:20 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 05:19:23 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:23 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 05:19:23 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 05:19:23 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 05:19:23 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 05:19:23 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 05:19:23 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 05:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19:24 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 05:19:24 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 05:19:24 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 05:19:24 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 05:19:24 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 05:19:24 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 05:19:24 INFO - Downloading docopt-0.6.1.tar.gz 05:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:24 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:24 INFO - Running setup.py install for blobuploader 05:19:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:24 INFO - Running setup.py install for requests 05:19:25 INFO - Running setup.py install for docopt 05:19:25 INFO - Successfully installed blobuploader requests docopt 05:19:25 INFO - Cleaning up... 05:19:25 INFO - Return code: 0 05:19:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:25 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')]} 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:25 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')]} 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:25 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:25 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 05:19:25 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 05:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:25 INFO - 'HOME': '/Users/cltbld', 05:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:25 INFO - 'LOGNAME': 'cltbld', 05:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:25 INFO - 'MOZ_NO_REMOTE': '1', 05:19:25 INFO - 'NO_EM_RESTART': '1', 05:19:25 INFO - 'PAGER': '/bin/cat', 05:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:25 INFO - 'PWD': '/builds/slave/test', 05:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:25 INFO - 'SHELL': '/bin/bash', 05:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:25 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:25 INFO - 'USER': 'cltbld', 05:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:25 INFO - 'XPC_FLAGS': '0x0', 05:19:25 INFO - 'XPC_SERVICE_NAME': '0', 05:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:25 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-97UpSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-e4tI9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-PtbEY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-qlbuz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-vB6sRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-3NBYhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-iuoB_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-rJFsRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-SUQF8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-xcHLfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:26 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-gLXBG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-fZTECe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-70dQ_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-jxwnfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-6mhaU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-Xwuu_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:27 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-amZ5jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:27 INFO - Running setup.py install for manifestparser 05:19:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:27 INFO - Running setup.py install for mozcrash 05:19:27 INFO - Running setup.py install for mozdebug 05:19:28 INFO - Running setup.py install for mozdevice 05:19:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Running setup.py install for mozfile 05:19:28 INFO - Running setup.py install for mozhttpd 05:19:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Running setup.py install for mozinfo 05:19:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Running setup.py install for mozInstall 05:19:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:28 INFO - Running setup.py install for mozleak 05:19:29 INFO - Running setup.py install for mozlog 05:19:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Running setup.py install for moznetwork 05:19:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Running setup.py install for mozprocess 05:19:29 INFO - Running setup.py install for mozprofile 05:19:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Running setup.py install for mozrunner 05:19:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:29 INFO - Running setup.py install for mozscreenshot 05:19:29 INFO - Running setup.py install for moztest 05:19:30 INFO - Running setup.py install for mozversion 05:19:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:30 INFO - Cleaning up... 05:19:30 INFO - Return code: 0 05:19:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:30 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:30 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 05:19:30 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 05:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:30 INFO - 'HOME': '/Users/cltbld', 05:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:30 INFO - 'LOGNAME': 'cltbld', 05:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:30 INFO - 'MOZ_NO_REMOTE': '1', 05:19:30 INFO - 'NO_EM_RESTART': '1', 05:19:30 INFO - 'PAGER': '/bin/cat', 05:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:30 INFO - 'PWD': '/builds/slave/test', 05:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:30 INFO - 'SHELL': '/bin/bash', 05:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:30 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:30 INFO - 'USER': 'cltbld', 05:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:30 INFO - 'XPC_FLAGS': '0x0', 05:19:30 INFO - 'XPC_SERVICE_NAME': '0', 05:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-JIwdII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:30 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)) 05:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-_WdqkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:30 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)) 05:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-s2nBVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:30 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)) 05:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:30 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-EbL08K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-zk8MUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-GiEKfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-tFEd30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-EGmn_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-IgPTW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-kleJgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RF7b6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-GVAY0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-DX3Ly3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:31 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)) 05:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:31 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-RjUbyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:32 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)) 05:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:32 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-b5w7CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:32 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)) 05:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:32 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-T1KdTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:32 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)) 05:19:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:32 INFO - Running setup.py (path:/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/pip-bnyYY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:32 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)) 05:19:32 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)) 05:19:32 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)) 05:19:32 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)) 05:19:32 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)) 05:19:32 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)) 05:19:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:32 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 05:19:32 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 05:19:32 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 05:19:32 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 05:19:32 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 05:19:32 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 05:19:34 INFO - Downloading blessings-1.6.tar.gz 05:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:35 INFO - Installing collected packages: blessings 05:19:35 INFO - Running setup.py install for blessings 05:19:35 INFO - Successfully installed blessings 05:19:35 INFO - Cleaning up... 05:19:35 INFO - Return code: 0 05:19:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:35 INFO - Reading from file tmpfile_stdout 05:19:35 INFO - Current package versions: 05:19:35 INFO - blessings == 1.6 05:19:35 INFO - blobuploader == 1.2.4 05:19:35 INFO - docopt == 0.6.1 05:19:35 INFO - functools32 == 3.2.3-2 05:19:35 INFO - jsonschema == 2.5.1 05:19:35 INFO - manifestparser == 1.1 05:19:35 INFO - mozInstall == 1.12 05:19:35 INFO - mozcrash == 1.0 05:19:35 INFO - mozdebug == 0.1 05:19:35 INFO - mozdevice == 0.49 05:19:35 INFO - mozfile == 1.2 05:19:35 INFO - mozhttpd == 0.7 05:19:35 INFO - mozinfo == 0.9 05:19:35 INFO - mozleak == 0.1 05:19:35 INFO - mozlog == 3.4 05:19:35 INFO - moznetwork == 0.27 05:19:35 INFO - mozprocess == 0.25 05:19:35 INFO - mozprofile == 0.28 05:19:35 INFO - mozrunner == 6.13 05:19:35 INFO - mozscreenshot == 0.1 05:19:35 INFO - mozsystemmonitor == 0.3 05:19:35 INFO - moztest == 0.8 05:19:35 INFO - mozversion == 1.4 05:19:35 INFO - psutil == 3.1.1 05:19:35 INFO - requests == 1.2.3 05:19:35 INFO - wsgiref == 0.1.2 05:19:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 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 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:35 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 05:19:35 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 05:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:35 INFO - 'HOME': '/Users/cltbld', 05:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:35 INFO - 'LOGNAME': 'cltbld', 05:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:35 INFO - 'MOZ_NO_REMOTE': '1', 05:19:35 INFO - 'NO_EM_RESTART': '1', 05:19:35 INFO - 'PAGER': '/bin/cat', 05:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:35 INFO - 'PWD': '/builds/slave/test', 05:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:35 INFO - 'SHELL': '/bin/bash', 05:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:35 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:35 INFO - 'USER': 'cltbld', 05:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:35 INFO - 'XPC_FLAGS': '0x0', 05:19:35 INFO - 'XPC_SERVICE_NAME': '0', 05:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 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)) 05:19:35 INFO - Cleaning up... 05:19:35 INFO - Return code: 0 05:19:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f996a556b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, '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.2xYvrUiCr0/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.yh9fviKmc0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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 05:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:35 INFO - 'HOME': '/Users/cltbld', 05:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:35 INFO - 'LOGNAME': 'cltbld', 05:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:35 INFO - 'MOZ_NO_REMOTE': '1', 05:19:35 INFO - 'NO_EM_RESTART': '1', 05:19:35 INFO - 'PAGER': '/bin/cat', 05:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:35 INFO - 'PWD': '/builds/slave/test', 05:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:35 INFO - 'SHELL': '/bin/bash', 05:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:35 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:35 INFO - 'USER': 'cltbld', 05:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:35 INFO - 'XPC_FLAGS': '0x0', 05:19:35 INFO - 'XPC_SERVICE_NAME': '0', 05:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:19:36 INFO - Cleaning up... 05:19:36 INFO - Return code: 0 05:19:36 INFO - Running post-action listener: _resource_record_post_action 05:19:36 INFO - Running post-action listener: _start_resource_monitoring 05:19:36 INFO - Starting resource monitoring. 05:19:36 INFO - [mozharness: 2017-04-06 12:19:36.165494Z] Finished create-virtualenv step (success) 05:19:36 INFO - [mozharness: 2017-04-06 12:19:36.166256Z] Running install step. 05:19:36 INFO - Running pre-action listener: _resource_record_pre_action 05:19:36 INFO - Running main action method: install 05:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:36 INFO - Reading from file tmpfile_stdout 05:19:36 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:36 INFO - Reading from file tmpfile_stdout 05:19:36 INFO - Output received: 05:19:36 INFO - Usage: mozinstall [options] installer 05:19:36 INFO - Options: 05:19:36 INFO - -h, --help show this help message and exit 05:19:36 INFO - -d DEST, --destination=DEST 05:19:36 INFO - Directory to install application into. [default: 05:19:36 INFO - "/builds/slave/test"] 05:19:36 INFO - --app=APP Application being installed. [default: firefox] 05:19:36 INFO - mkdir: /builds/slave/test/build/application 05:19:36 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'] 05:19:36 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 05:19:56 INFO - Reading from file tmpfile_stdout 05:19:56 INFO - Output received: 05:19:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:19:56 INFO - Running post-action listener: _resource_record_post_action 05:19:56 INFO - [mozharness: 2017-04-06 12:19:56.345262Z] Finished install step (success) 05:19:56 INFO - [mozharness: 2017-04-06 12:19:56.345404Z] Running run-tests step. 05:19:56 INFO - Running pre-action listener: _resource_record_pre_action 05:19:56 INFO - Running main action method: run_tests 05:19:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:56 INFO - 2017-04-06 05:19:56.360 screenresolution[1623:7422] starting screenresolution argv=screenresolution get 05:19:56 INFO - 2017-04-06 05:19:56.362 screenresolution[1623:7422] Display 0: 1600x1200x32@60 05:19:56 INFO - 2017-04-06 05:19:56.368 screenresolution[1624:7424] starting screenresolution argv=screenresolution list 05:19:56 INFO - Available Modes on Display 0 05:19:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:56 INFO - Intel Iris: 05:19:56 INFO - Chipset Model: Intel Iris 05:19:56 INFO - Type: GPU 05:19:56 INFO - Bus: Built-In 05:19:56 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:56 INFO - Vendor: Intel (0x8086) 05:19:56 INFO - Device ID: 0x0a2e 05:19:56 INFO - Revision ID: 0x0009 05:19:56 INFO - Displays: 05:19:56 INFO - Display: 05:19:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:56 INFO - Display Serial Number: 1600x1200 60 05:19:56 INFO - Main Display: Yes 05:19:56 INFO - Mirror: Off 05:19:56 INFO - Online: Yes 05:19:56 INFO - Rotation: Supported 05:19:56 INFO - Return code: 0 05:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:56 INFO - Python 2.7.3 05:19:56 INFO - Return code: 0 05:19:56 INFO - grabbing minidump binary from tooltool 05:19:56 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')]} 05:19:56 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 0x10a693d30>, '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 0x10a5badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a688be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:56 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 05:19:56 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 05:19:56 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 05:19:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:56 INFO - Return code: 0 05:19:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:56 INFO - ENV: RUST_BACKTRACE is now 1 05:19:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:56 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/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 05:19:56 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/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0142 --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 05:19:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2xYvrUiCr0/Render', 05:19:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:56 INFO - 'HOME': '/Users/cltbld', 05:19:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:56 INFO - 'LOGNAME': 'cltbld', 05:19:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:56 INFO - 'MOZ_NO_REMOTE': '1', 05:19:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:56 INFO - 'NO_EM_RESTART': '1', 05:19:56 INFO - 'PAGER': '/bin/cat', 05:19:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:56 INFO - 'PWD': '/builds/slave/test', 05:19:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:56 INFO - 'RUST_BACKTRACE': '1', 05:19:56 INFO - 'SHELL': '/bin/bash', 05:19:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yh9fviKmc0/Listeners', 05:19:56 INFO - 'TMPDIR': '/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/', 05:19:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:56 INFO - 'USER': 'cltbld', 05:19:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:56 INFO - 'XPC_FLAGS': '0x0', 05:19:56 INFO - 'XPC_SERVICE_NAME': '0', 05:19:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:56 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/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0142', '--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 05:19:56 INFO - mozversion application_buildid: 20170406041033 05:19:56 INFO - mozversion application_changeset: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a 05:19:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:19:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:56 INFO - mozversion application_name: Firefox 05:19:56 INFO - mozversion application_remotingname: firefox-dev 05:19:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:56 INFO - mozversion application_vendor: Mozilla 05:19:56 INFO - mozversion application_version: 54.0a2 05:19:56 INFO - mozversion platform_buildid: 20170406041033 05:19:56 INFO - mozversion platform_changeset: 9d164f1dc3c2eac75e31c57aa294b5a4d1b0030a 05:19:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:19:56 INFO - mozversion platform_version: 54.0a2 05:19:56 INFO - using testdate: 1491481196 05:19:56 INFO - actual date: 1491481196 05:19:56 INFO - starting webserver on 'localhost:49240' 05:19:56 INFO - SUITE-START | Running 2 tests 05:19:56 INFO - TEST-START | dromaeo_css 05:19:56 INFO - operating with platform_type : mac_ 05:19:56 INFO - Initialising browser for dromaeo_css test... 05:19:56 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpdowVKu/profile http://localhost:49240/getInfo.html) 05:19:59 INFO - PROCESS | 1634 | 2017-04-06 05:19:59.851 plugin-container[1636:7597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 05:19:59 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:59 INFO - PROCESS | 1634 | 2017-04-06 05:19:59.854 plugin-container[1636:7597] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 05:19:59 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:19:59 INFO - PROCESS | 1634 | 2017-04-06 05:19:59.854 plugin-container[1636:7597] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:19:59 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 05:19:59 INFO - PROCESS | 1634 | colorDepth:24 05:19:59 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 05:19:59 INFO - PROCESS | 1634 | __metrics 05:20:00 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 05:20:00 INFO - TEST-INFO | 1634: exit 0 05:20:00 INFO - Browser initialized. 05:20:00 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:00 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpdowVKu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:03 INFO - PROCESS | 1637 | 2017-04-06 05:20:03.326 plugin-container[1638:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 05:20:03 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:03 INFO - PROCESS | 1637 | 2017-04-06 05:20:03.326 plugin-container[1638:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 05:20:03 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:20:03 INFO - PROCESS | 1637 | 2017-04-06 05:20:03.326 plugin-container[1638:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:20:12 INFO - PROCESS | 1637 | RSS: Main: 221151232 05:20:12 INFO - PROCESS | 1637 | 05:23:16 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 05:23:18 INFO - PROCESS | 1637 | RSS: Main: 242098176 05:23:18 INFO - PROCESS | 1637 | 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 05:25:01 INFO - PROCESS | 1637 | 05:25:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:25:01 INFO - PROCESS | 1637 | Full stack: 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 05:25:01 INFO - PROCESS | 1637 | 05:25:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:25:01 INFO - PROCESS | 1637 | Full stack: 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 05:25:01 INFO - PROCESS | 1637 | 05:25:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:25:01 INFO - PROCESS | 1637 | Full stack: 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | ************************* 05:25:01 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 05:25:01 INFO - PROCESS | 1637 | 05:25:01 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:25:01 INFO - PROCESS | 1637 | Full stack: 05:25:01 INFO - PROCESS | 1637 | ************************* 05:26:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 05:26:21 INFO - PROCESS | 1637 | RSS: Main: 250572800 05:26:21 INFO - PROCESS | 1637 | 05:29:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 05:29:23 INFO - PROCESS | 1637 | RSS: Main: 258117632 05:29:23 INFO - PROCESS | 1637 | 05:32:26 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 05:32:26 INFO - PROCESS | 1637 | RSS: Main: 254427136 05:32:26 INFO - PROCESS | 1637 | 05:35:29 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:35:30 INFO - PROCESS | 1637 | RSS: Main: 260075520 05:35:30 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:38:33 INFO - PROCESS | 1637 | RSS: Main: 256114688 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | __start_tp_report 05:38:33 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 05:38:33 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 05:38:33 INFO - PROCESS | 1637 | |i|pagename|runs| 05:38:33 INFO - PROCESS | 1637 | |0;dojo.html;3022.667506574275;3469.358168738783;3483.233688588511;3489.860405583777;3512.877049303275;5011.523905228997;5326.748214169676;6012.308584512777;6017.578769486137;6056.818295451132;6034.487068599164;6056.606320589159;6092.87814243716;6112.327643959172;6117.082437634357;5435.891282174354;5440.292761940962;5533.750981205855;5575.386707462176;5603.915941260864;4701.7414042227665;5459.863503412426;5489.39616642169;5588.4411558844195;5591.329040515144;5241.977814326208;5298.470152984709;5343.625946183769;5477.233187353793;5489.670619762827;4207.873763787091;4898.608111351084;5365.517103460707;5378.273933019071;5414.431484694129;5388.703621300827;5397.865053373697;5409.2967914171295;5452;5464.207689884985;5150.686574923408;5183.922241166386;5972.134040564094;6057.000594901836;6134.539909506805;6203.4416902479;6238.407351301619;6381.93618063818;6391.105245265667;6399.520035997319;6348.682565871688;6402.775902843425;6410.358964103599;6420.5184611154355;6428.067930150149;6182.010878259547;6195.0707393890825;6198.380161983856;6200.038993955944;6219.595726277823;6474.546781725328;6612.173478315211;6616.603003819786;6644.368784965421;6649.268580456146;6209.627422354673;6216.720247588779;6234.407731265523;6253.624782512973;6304.3695630437505;6461.127747754087;6468.288488266287;6468.514861385415;6481.546291759531;6482.740690372332;6263.279722831841;6353.745850166034;6361.300256971729;6395.104685344056;6397.552171348054;5032.874178145576;6252.781152659726;6262.780802671976;6274.372562743689;6296.275928268216;5149.9742501287255;5155.355580552431;5160.612954028463;5171.7155556444;5199.896002079937;4492.168948744493;4604.125216208977;4608.124456353282;4621.861344159681;4646.465656449552;4972.900541989212;5873.236479257669;5891.49922256613;5951.553633477506;6042.939570604326;6440.291567927524;6443.67781610927;6444.581102228341;6454.806355809334;6461.0631458438265;5990.850228744316;6039.969800150971;6047.8488037798525;6054.212952316258;6064.787732429344;5452.100403433389;5542.196381524737;5553.222548843165;5609;5656.830295091154;5932.673702946379;6738.157730283812;6752.763653272112;6755.729770809113;6769.932300677127;6183.258009038944;6403.423691867754;6411.294757576569;6413.422791948747;6433.517486188461;6304.621722696653;6701.296363881722;6706;6761;6777.525573210025;4733.502982186821;4934.679245849008;5500.889982200253;5533.280673512418;6260.5930614511735;6335.936640633719;6349.682515874096;6379.106925030406;6386.457151142019;6461.838454038687;6131.0803379491645;6250.249970003629;6280.183576135073;6283.874322513433;6336.746530138743;6121.666376154749;6156.107364432221;6174.413430724074;6223.533235007302;6245.344238855037;5321.840344789663;6073.635581865102;6168.228971378578;6212.81361559154;6248.250209974651;6242.001279795211;6256;6273.811785646438;6286.811395658137;6289.119523266838;6071.210742603434;6124.38756124384;6185.8144255672405;6193.84515387119;6259.561830671809 05:38:33 INFO - PROCESS | 1637 | |1;ext.html;751.8308380614362;810.5987536169595;825.4057078903191;826.9710560130396;955.6225291010055;38849.80575097124;39663.80168099163;39690;39734;39824.800875995614;38268;38472.61527384725;38489.037774055665;38538.65114720985;38590;7768.990031295938;7955.124936257007;10319;10591.576336946513;10599.20505962052;10272.229582781283;10467.371957682526;10469.31949423285;10476.319039262424;10486.632967846126;10451.58193672256;10499.42253176075;10515.579376824919;10525.157987361032;10532.526036328383;10427.270091093627;10443.791124177513;10460.215483838743;10466.895331046668;10486.528106235199;8808.427452215585;10187.134093602053;10272.33229840058;10280.537375818032;10316.432596207205;29645.555316670267;31647.367052659047;33448.331033379436;33523.32953340944;33545.6645433545;3402.0814380117477;3719.237556300952;3743.4777634635507;3789.810509474543;3792.6207379261855;24540.386490337885;24906.12828550992;25190.874045629655;25336.239912802645;26203.344916377053;2644.2331723800075;3056.037348235299;3076.384723055398;3119.2201949512623;3144.811311321328;401942.9999999971;403005;447238.99999999674;464571;467509;35619;42931.9267018327;43163.78418107952;43223;43280;45309.546904530536;45476.54523454723;45562;45645.771771140935;45689.31466028012;13176.60470185896;13279.867201327863;13291.3354332283;13306.800397994039;13442.529511467052;9395.765105872271;11333.376664283544;11515.078793696652;11517.078633709283;11595.594154204731;10445.11216546587;11747.941260293645;11763.705907352385;11781.646550603497;11876.821847672292;5181.6631918925905;5468.671879687232;5474.589405794582;5478.616496845262;5488.17677348401;1119.2780656476527;1138.135017386792;1142.5486932661552;1159.646307876089;1187.1511869013696;26177.607335889978;26333.868330658224;26500.337491562866;26531.336716581853;26535.93856245767;1140.1733743036398;1241.4227384266178;1241.5344245907784;1257.8050402187675;1257.9433925473375;13546.864531354757;13591.592252232449;13781.655458613413;13806.964477664014;13839.377228024763;396265;397532;397722;397811;399782;53767.462325377026;53856.92286154333;53956;54190.91618167615;54610.72694636581;1381.7927310903444;1392.3525560614362;1415.4288739499148;1422.73679369317;1424.0245431879196;24962.875185623954;27541.586876197274;27726.861365693043;27796.166115016582;27872.44255114927;33955.66044339525;41002.79498602488;41236.38145427881;41267.58732412758;41302;410128;448628;460884.69557652;461487;463208.6839565781;25488.8530016146;29615.703842961295;29893.70106298909;29923.55114673238;30140.54789178208;38274.2345153101;39110;39284;39440;39456.2108757829;25986.48027039411;28276.575851361835;28394.148175554765;28692.85228590833;28713.995010174545;27850.860745696144;28149.71850281553;28265.858670706515;28302;29367.55948660729;30040;32380.83809580937;32921.01236962895;32966;33150.005499835046;23009.539809204056;25002.624960625242;27955.440891181657;28140.85929570339;28980.565291520223;19315.130819112892;21111.8944405277;21832.126714931226;23018.654720178874;23110.30669079863 05:38:33 INFO - PROCESS | 1637 | |2;jquery.html;387.44595228822783;417.85375118708436;419.5971867007672;435.6057767720212;462.27653721925185;18123.365682201125;19608.215671373127;19750.407487775356;20114.597708045847;20949.68575471367;20897.582048359025;21195.788042119573;21240;21981.010854511544;22091.889540552274;6269.561130720851;6382.680865956707;6408.038794180864;6424.71088801005;6433.710483028275;6333.778317758879;6811.284815094418;7150.5352152109945;7271.89092163618;7275.1633562140505;7023.61370124643;7173.354398104169;7205.279472052805;7245.89131163033;7282.672279747398;7101.076860008166;7136.500444968854;7178.2103968563415;7205.819854503627;7215.891761623579;6603.405693487609;6958.8956165657555;7053.365197132282;7062.646867656596;7068;23068.76931230683;24766.25701228966;25308.49383012348;25442.745572544223;25498;2772.778177745796;2885.6104425902445;2886.235147685865;2892.2335581071034;2915.6646985596717;15564.132393986467;15997.360105595762;16184.676306473804;16233.51299461018;16667.83325167239;2063.2778527515397;2089.8119169274687;2099.170827523135;2108.7785782492924;2140.2616097446357;91188.63217051749;107382;135112;139231;149958.25020874935;15152.469663561722;16230.837691622932;16569.585760356087;20488.824232244027;20601.58796824055;31099.84450077735;31154.909578164654;31187.220319491742;31367;31367.529487057713;10136.037076477724;10182.847257291147;10242.48787560619;10382.636607718845;10465.790684186273;8499.70251041223;8512.70205542803;8527.062023177445;8637;8666.696665616673;9397.201237894855;9417.90582094184;9585.760355991157;9588.424694518351;9602.991685872883;4138.6482149017675;4228.746275223496;4258.87223383299;4278.593533614302;4410.448693913261;997.5162046407487;1007.9042490963346;1015.8882522922472;1017.9287449878437;1049.6955882793904;16890.74663880043;20503.4874128148;20514.897425512776;20537.89731051365;20578.38264852057;1005.7234260578253;1010.6412223660642;1033.3180101133216;1039.1686650679426;1040.9323393979366;10003.79992400148;10272.280940334254;10347.534360953776;10377.325473844176;10378.896211037792;721558;740469;742479;753871;756633;120768.39615801864;141291;142180;148265;149042;1103.6027030269097;1109.3953795181485;1178.5403692559908;1213.3326670331282;1259.2444533279959;14999.575040371148;17630.82369176292;19737.80262197417;19806;19806.801931980495;57759;58353.124703130336;58361;58400.70799645974;60420.69789651024;70458.94311585433;80264;81766.18233817823;82158.58920705598;83628.58185709032;9037.77405564866;10448.947755261175;10635.096016838655;10795;11243.66269011931;11601.883981160418;11782.705432364259;12106.515739370327;12135.150539462153;12174.634760957186;9891.109800118023;9924.950375248078;10010.249231307535;10036.447995360326;10261.230407719302;9573.856392153984;9734.853977190207;9790.06994335537;9803;9839.01609839039;10288.074073333435;10326.845097323696;10403.479826008821;10451.634192803216;10619.36283822973;8284.917150828414;10690.877457867124;10797.460126993776;11036.889631103908;11069.83395249088;10038.247131465023;10112.292139550455;10333.689989300334;10366.740831479274;10379.688609341732 05:38:33 INFO - PROCESS | 1637 | |3;mootools.html;1523.7638166084248;1566.6631674190055;1573.2920185916341;1586.4447443394813;1591.816941051779;10115.494225288734;11119.666410007712;11200.327980321175;11206.991370776628;11415.31508109514;11005.119590432767;11142.387168705718;11192.048675862541;11204.551817927293;11234.662960111189;2432.793212576933;2474.2206205045354;2474.7772700456953;2478.281298423455;2480.0327872129883;2414.1550457339877;2425.987870060656;2470.209532949457;2471.0115953618515;2473.468204336067;2425.4785221177444;2426.3085020769086;2443.5235129149823;2463.08865719684;2474.950500989979;2087.332053742803;2251.324602619208;2434.610462326028;2442.535918175541;2449.216250799745;2137.925172618951;2414.625733011386;2428.987855060713;2448.7796098351055;2449.326435230308;10644.627438039632;10703.250772445932;10714.678559643224;10801.189910756646;10812.026917577377;5491.28613280275;5627.324721033502;5650.660960342392;5674.460926212003;5687.146927960797;3106.6427360853563;3141.8743250269963;3157.321175947159;3750.587435382106;3772.8868133956025;1200.5497938273147;1202.3988005997;1238.0467040378949;1257.5535684831843;1259.0745801835644;13816.93091534536;14018.228997405138;14338.713225735426;14461.855381446261;14761.483348082873;4704.600108990706;4748.287756836502;4748.928766068511;4868.659193856467;4903.53416425439;11534.077273818075;11597.304161750322;11642.6507204784;11658.00906922921;11666.300021998708;4957.429895562056;5797.246357973437;5891.351951285355;5893.5579831512805;5895.380984996514;4404.405405270311;5263.868403289872;5279.049771041249;5356.973215133899;5400.702961337086;3913.1977944521304;3983.980080099581;4008.959910400917;4057.8173982170874;4069.064115253508;1076.956921723138;1077.3320541264466;1083.7453198498347;1092.415557676655;1095.3756358875523;918.7703074231442;936.180841763457;950.2493030505962;955.861400096982;996.7607774134301;6457.870842583122;6481.449076828428;6602.2737498875085;6695.4308883745425;6770.344951419464;957.066859811678;983.1643103362085;1001.5392919257077;1009.1422291052693;1027.9383237005804;4164.250434921743;4201.6008479194425;4209.7474151551005;4298.613124818781;4316.676249281317;13333.733325333633;13952.651183720285;13974.882009439221;13989.930050349887;14027.719445611236;12923.483060677656;13428.597142085753;13761.793573096411;13882.861171388156;13904.513342033182;8473.576321183793;9984.650537231162;10272.743181420525;10275.948620256851;10295.07344339013;5791.53667706574;5938.61399009063;6770.086038384952;6905.75829845953;6910.136232970879;12305.692357691129;12438.875611243771;12445.937770311091;12492.37538123072;12516.061295403064;12505;12537.373131343578;12560.497580096693;12798.16811907223;12880.35598220104;9172.266218702354;9245.491497967676;9282.72151835446;9368.437893726126;9410.717678469657;10191;10218.284720069822;10258.43578603184;10316.535755890853;10338.431386273827;8738.563071846509;8762.474251544927;8766.342524310574;8807.647694092164;8809.898762654668;8479.95760021196;8527;8595.226429621363;8644.91355086441;8752.51861147643;7846.686132554634;7857.764267071997;7867.685292588232;7958.442908996546;7988.081370642218;5755.251817263729;5886.499647530008;5911.231539899785;5919.644821310563;5953.910691339547;4889.437714662859;5225.608079393985;5855.473007429352;5904.468597826216;6085.421884920926 05:38:33 INFO - PROCESS | 1637 | |4;prototype.html;378.4304621544575;389.38865980410765;393.67915149153447;395.9901002474938;396.9238402381544;4584.610308123813;4775.230366387495;4916.337328690126;4936.851894443168;5206.43806857942;4282.464691913511;4410.558944105588;4685.344051832746;4995.5753760930365;5109.540141387274;3676.522052133219;4097.713160078795;4098.549159592442;4110.7122501424765;4147.398627199054;3651.4522821576843;4139.482564679415;4146.108586653869;4188.958110418903;4190.664746820263;4133.049398638315;4134.276501612221;4139.379093136024;4156.7945295864165;4187.796335803113;3901.4691704007637;3948.8420463181583;4004;4099.549049604541;4128.401381799622;3634.818259087035;3644.2711457708565;3650.4159334506617;3659.5608526976666;3661.3226553087497;4471.888202794924;4623.653226008063;4727.203662608221;4977.7511124444;5225.268462415265;4254.765987870666;4500.707454015478;4593.27944365062;4686.656671664168;4845.892036963932;5612.270404847384;5634.830955071355;5657.830265092054;5681.147827825818;5681.659100453986;4048.0164719287336;4064.878053658395;4065.7560546367313;4077.7757223352705;4130.442390277304;5373.086575282172;5529.529989950859;5825.388334224887;5885.704575215669;5938.257717785277;4035.8183881724804;4391.824327026947;4471.1728330259;4481.3726078349055;4582.350353872604;4360.280553708666;4646.326282689059;4657.790399431966;4667.6732628716345;4690.882727931761;4713.622910167179;4799.904001920012;4947.6108865427295;5021.949780502221;5066.493350664978;5435.901536124371;5612.719364031782;5634.464725851038;5665.716714164275;5726;1373.4368908747365;1429.8355689095679;1573.7796708460824;1576.8107827060826;1588.864946479539;999.9850002249972;1094.4746521669642;1190.922590031645;1196.760647870412;1197.5569039455456;4738.431388233434;5144.38267407906;5182.170852663556;5195.3115237547945;5261.657992230569;5430.91853622196;5610.887782244332;5687.805560832189;5726.77092916287;5853.795117170964;4721.693089949211;4770.76146192689;4952.455229924705;4960.156773348539;5210.687358758487;2949.2037149969815;2952.5571164325524;3020.776585482883;3057.2968217310145;3057.5107982722657;5208.661437006583;5746.684317006752;5882.294124704978;6112.724927378279;6160.291566469824;5639.416977192632;5781.739821708034;6010.0083486224285;6072.332043475214;6123.755049797958;5916.473897468354;6009.338972712998;6051.88184135549;6056.727447264883;6081.452669259788;5617.269754931833;5813.912791308049;5843.328017278068;5883.411658834082;5889;4993.925091123563;5222;5527.806526771705;5542.944570554404;5861.0915308127305;5550.5282051026115;5632.127020311858;6058.878822423439;6154.907676384947;6160.815175544741;4321.027768752006;4544.22775117711;4616.746078965689;4621.83823566187;4652.32541281519;5493.533049690821;5775.422457754359;5788.363280039108;5840.766369345349;5849.151872978479;4960.751962401793;5272.578193744414;5442.863928401822;5644.407337229532;5677.801276955287;5265.6840589564745;5304.549113325411;5531.861703457284;5573.832785016456;5581.665100094007;4819.638527110551;5314.7874085036165;5539.944600553943;5557.666540007451;5566.721663916869;5507.807226747205;5576.769292283119;5663.9716801415725;5686.630369026;5692.914606280826;5173.793048278178;5498.477644623755;5501.771782428123;5540.944590554042;5560.443955604407 05:38:33 INFO - PROCESS | 1637 | |5;yui.html;720.7405334079727;751.3876190904413;761.7295859969707;771.2249189564487;851.2462184832067;14426.567202983913;14951.700965980688;15004.624884377883;17064.658706825856;18185.272589096454;16964.3214271429;17117.657646847056;17130.229139688712;17153.970761754303;17213.139343032828;945.2060269373742;962.7881865989506;968.1818863060703;968.4141094637748;971.3928794503436;926.2358554192784;945.0266225787416;966.7099870038995;967.5307475874196;971.9902800972006;903.4488961733338;948.0993056596227;976.623999760094;995.258532393368;997.9201663866874;967.2938754709102;979.7354714227155;987.476637382185;989.5101924547323;990.074280547683;695.8782213112685;698.4203111417511;747.8130467383154;749.7750674797594;750.5271678842349;14622.707545849129;14925.776113358308;15554;16252.106134162615;16537;13749;14096.859031409656;14131.646708832259;14138.71722565543;14204.360803763855;11652.94173529127;11787.823182652268;11797.823032654516;11851.703707407383;11945.940270298679;3572.3034008368377;3622.2212224371888;3636.581793093775;3658.359787037258;3806.0484878780303;92142;95135;98164.50917745366;98192.018079819;98585.5070724649;10461.372317660966;10668.07446734326;10686.198535109743;10701.304415212986;10719.678409647722;21766;22058.669119963215;22348.888255558617;22363.77636223617;22420.55158896813;7617.8954051662195;7687.346575541142;7692.7692169235015;7719.266669666373;7720.922790772021;4160.667146628262;4294.806733696991;4300.7634580097765;4307.375430562551;4382.649388048949;3326.4843949187916;3399.847006884696;3415.112070861594;3422.3155368926314;3448.8963531669624;560.2268868960889;602.9969850150809;618.2519151826341;635.4154178156108;636.1761518833122;647.498188903594;654.3489228217859;663.2074670768482;678.4063944048958;713.3437237741186;9588.952055239819;10310.693713501216;10353.896461035294;10475.1096156826;10561.471926403648;638.396715104222;644.8682562103478;668.3483603486561;704.1198501872659;705.1714235772946;3864.42033694948;4567.862964111082;4955.40535135786;4966.875828104254;4972.105021096165;64910;98932;100153.49923250337;102509.48745256376;102991.4850425743;59290;60697.39302607006;82018;85969.57015214884;89547.55226223958;64782.704345913764;68173;70639.64680176566;71540.2845971544;72453;10729.839052414376;11092.944535277595;11253.549858005916;11312.377819220023;11318.32090074598;44512.10975780531;45040;45140.54859451363;47235.527644723115;47554.76222618865;52609.210861836335;55605.72197138988;59784.70107649434;59941;60023;11990.760184796429;13008.414621342252;13360.532381366605;13395.933020334836;14066.507672231832;14616.269186540843;14689.659292299659;15008.774868376764;15013.474528391454;15343.309551070452;13531.557916160014;13811.240381779098;14562.563123106323;14606.926965365104;14735.2632368379;12798.68003299925;13476.797848032484;14493.710125797214;14776.63058423548;15308.846911530742;13468.98982576291;13550.390232439368;13679.79480307816;13703.246321452018;13958.23229722375;9247.80784602217;9897.307188497025;9897.802043959224;9901.851472227778;10438.843417348595;8905.465672059698;10132.405355700079;10894.891051089387;11726.296422214695;11775.882241177478 05:38:33 INFO - PROCESS | 1637 | __end_tp_report 05:38:33 INFO - PROCESS | 1637 | __start_cc_report 05:38:33 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,408 05:38:33 INFO - PROCESS | 1637 | __end_cc_report 05:38:33 INFO - PROCESS | 1637 | __startTimestamp1491482313469__endTimestamp 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | ------- Summary: start ------- 05:38:33 INFO - PROCESS | 1637 | Number of tests: 6 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5879.87 Median:6156.11 stddev:705.70 (11.5%) stddev-sans-first:671.32 05:38:33 INFO - PROCESS | 1637 | Values: 3022.7 3469.4 3483.2 3489.9 3512.9 5011.5 5326.7 6012.3 6017.6 6056.8 6034.5 6056.6 6092.9 6112.3 6117.1 5435.9 5440.3 5533.8 5575.4 5603.9 4701.7 5459.9 5489.4 5588.4 5591.3 5242.0 5298.5 5343.6 5477.2 5489.7 4207.9 4898.6 5365.5 5378.3 5414.4 5388.7 5397.9 5409.3 5452.0 5464.2 5150.7 5183.9 5972.1 6057.0 6134.5 6203.4 6238.4 6381.9 6391.1 6399.5 6348.7 6402.8 6410.4 6420.5 6428.1 6182.0 6195.1 6198.4 6200.0 6219.6 6474.5 6612.2 6616.6 6644.4 6649.3 6209.6 6216.7 6234.4 6253.6 6304.4 6461.1 6468.3 6468.5 6481.5 6482.7 6263.3 6353.7 6361.3 6395.1 6397.6 5032.9 6252.8 6262.8 6274.4 6296.3 5150.0 5155.4 5160.6 5171.7 5199.9 4492.2 4604.1 4608.1 4621.9 4646.5 4972.9 5873.2 5891.5 5951.6 6042.9 6440.3 6443.7 6444.6 6454.8 6461.1 5990.9 6040.0 6047.8 6054.2 6064.8 5452.1 5542.2 5553.2 5609.0 5656.8 5932.7 6738.2 6752.8 6755.7 6769.9 6183.3 6403.4 6411.3 6413.4 6433.5 6304.6 6701.3 6706.0 6761.0 6777.5 4733.5 4934.7 5500.9 5533.3 6260.6 6335.9 6349.7 6379.1 6386.5 6461.8 6131.1 6250.2 6280.2 6283.9 6336.7 6121.7 6156.1 6174.4 6223.5 6245.3 5321.8 6073.6 6168.2 6212.8 6248.3 6242.0 6256.0 6273.8 6286.8 6289.1 6071.2 6124.4 6185.8 6193.8 6259.6 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:54889.99 Median:28847.28 stddev:113999.60 (395.2%) stddev-sans-first:114246.94 05:38:33 INFO - PROCESS | 1637 | Values: 751.8 810.6 825.4 827.0 955.6 38849.8 39663.8 39690.0 39734.0 39824.8 38268.0 38472.6 38489.0 38538.7 38590.0 7769.0 7955.1 10319.0 10591.6 10599.2 10272.2 10467.4 10469.3 10476.3 10486.6 10451.6 10499.4 10515.6 10525.2 10532.5 10427.3 10443.8 10460.2 10466.9 10486.5 8808.4 10187.1 10272.3 10280.5 10316.4 29645.6 31647.4 33448.3 33523.3 33545.7 3402.1 3719.2 3743.5 3789.8 3792.6 24540.4 24906.1 25190.9 25336.2 26203.3 2644.2 3056.0 3076.4 3119.2 3144.8 401943.0 403005.0 447239.0 464571.0 467509.0 35619.0 42931.9 43163.8 43223.0 43280.0 45309.5 45476.5 45562.0 45645.8 45689.3 13176.6 13279.9 13291.3 13306.8 13442.5 9395.8 11333.4 11515.1 11517.1 11595.6 10445.1 11747.9 11763.7 11781.6 11876.8 5181.7 5468.7 5474.6 5478.6 5488.2 1119.3 1138.1 1142.5 1159.6 1187.2 26177.6 26333.9 26500.3 26531.3 26535.9 1140.2 1241.4 1241.5 1257.8 1257.9 13546.9 13591.6 13781.7 13807.0 13839.4 396265.0 397532.0 397722.0 397811.0 399782.0 53767.5 53856.9 53956.0 54190.9 54610.7 1381.8 1392.4 1415.4 1422.7 1424.0 24962.9 27541.6 27726.9 27796.2 27872.4 33955.7 41002.8 41236.4 41267.6 41302.0 410128.0 448628.0 460884.7 461487.0 463208.7 25488.9 29615.7 29893.7 29923.6 30140.5 38274.2 39110.0 39284.0 39440.0 39456.2 25986.5 28276.6 28394.1 28692.9 28714.0 27850.9 28149.7 28265.9 28302.0 29367.6 30040.0 32380.8 32921.0 32966.0 33150.0 23009.5 25002.6 27955.4 28140.9 28980.6 19315.1 21111.9 21832.1 23018.7 23110.3 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:40971.18 Median:11690.13 stddev:123106.23 (1053.1%) stddev-sans-first:123413.86 05:38:33 INFO - PROCESS | 1637 | Values: 387.4 417.9 419.6 435.6 462.3 18123.4 19608.2 19750.4 20114.6 20949.7 20897.6 21195.8 21240.0 21981.0 22091.9 6269.6 6382.7 6408.0 6424.7 6433.7 6333.8 6811.3 7150.5 7271.9 7275.2 7023.6 7173.4 7205.3 7245.9 7282.7 7101.1 7136.5 7178.2 7205.8 7215.9 6603.4 6958.9 7053.4 7062.6 7068.0 23068.8 24766.3 25308.5 25442.7 25498.0 2772.8 2885.6 2886.2 2892.2 2915.7 15564.1 15997.4 16184.7 16233.5 16667.8 2063.3 2089.8 2099.2 2108.8 2140.3 91188.6 107382.0 135112.0 139231.0 149958.3 15152.5 16230.8 16569.6 20488.8 20601.6 31099.8 31154.9 31187.2 31367.0 31367.5 10136.0 10182.8 10242.5 10382.6 10465.8 8499.7 8512.7 8527.1 8637.0 8666.7 9397.2 9417.9 9585.8 9588.4 9603.0 4138.6 4228.7 4258.9 4278.6 4410.4 997.5 1007.9 1015.9 1017.9 1049.7 16890.7 20503.5 20514.9 20537.9 20578.4 1005.7 1010.6 1033.3 1039.2 1040.9 10003.8 10272.3 10347.5 10377.3 10378.9 721558.0 740469.0 742479.0 753871.0 756633.0 120768.4 141291.0 142180.0 148265.0 149042.0 1103.6 1109.4 1178.5 1213.3 1259.2 14999.6 17630.8 19737.8 19806.0 19806.8 57759.0 58353.1 58361.0 58400.7 60420.7 70458.9 80264.0 81766.2 82158.6 83628.6 9037.8 10448.9 10635.1 10795.0 11243.7 11601.9 11782.7 12106.5 12135.2 12174.6 9891.1 9925.0 10010.2 10036.4 10261.2 9573.9 9734.9 9790.1 9803.0 9839.0 10288.1 10326.8 10403.5 10451.6 10619.4 8284.9 10690.9 10797.5 11036.9 11069.8 10038.2 10112.3 10333.7 10366.7 10379.7 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6704.55 Median:2473.84 stddev:4174.24 (168.7%) stddev-sans-first:4167.80 05:38:33 INFO - PROCESS | 1637 | Values: 1523.8 1566.7 1573.3 1586.4 1591.8 10115.5 11119.7 11200.3 11207.0 11415.3 11005.1 11142.4 11192.0 11204.6 11234.7 2432.8 2474.2 2474.8 2478.3 2480.0 2414.2 2426.0 2470.2 2471.0 2473.5 2425.5 2426.3 2443.5 2463.1 2475.0 2087.3 2251.3 2434.6 2442.5 2449.2 2137.9 2414.6 2429.0 2448.8 2449.3 10644.6 10703.3 10714.7 10801.2 10812.0 5491.3 5627.3 5650.7 5674.5 5687.1 3106.6 3141.9 3157.3 3750.6 3772.9 1200.5 1202.4 1238.0 1257.6 1259.1 13816.9 14018.2 14338.7 14461.9 14761.5 4704.6 4748.3 4748.9 4868.7 4903.5 11534.1 11597.3 11642.7 11658.0 11666.3 4957.4 5797.2 5891.4 5893.6 5895.4 4404.4 5263.9 5279.0 5357.0 5400.7 3913.2 3984.0 4009.0 4057.8 4069.1 1077.0 1077.3 1083.7 1092.4 1095.4 918.8 936.2 950.2 955.9 996.8 6457.9 6481.4 6602.3 6695.4 6770.3 957.1 983.2 1001.5 1009.1 1027.9 4164.3 4201.6 4209.7 4298.6 4316.7 13333.7 13952.7 13974.9 13989.9 14027.7 12923.5 13428.6 13761.8 13882.9 13904.5 8473.6 9984.7 10272.7 10275.9 10295.1 5791.5 5938.6 6770.1 6905.8 6910.1 12305.7 12438.9 12445.9 12492.4 12516.1 12505.0 12537.4 12560.5 12798.2 12880.4 9172.3 9245.5 9282.7 9368.4 9410.7 10191.0 10218.3 10258.4 10316.5 10338.4 8738.6 8762.5 8766.3 8807.6 8809.9 8480.0 8527.0 8595.2 8644.9 8752.5 7846.7 7857.8 7867.7 7958.4 7988.1 5755.3 5886.5 5911.2 5919.6 5953.9 4889.4 5225.6 5855.5 5904.5 6085.4 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4668.82 Median:4994.75 stddev:1341.40 (26.9%) stddev-sans-first:1305.93 05:38:33 INFO - PROCESS | 1637 | Values: 378.4 389.4 393.7 396.0 396.9 4584.6 4775.2 4916.3 4936.9 5206.4 4282.5 4410.6 4685.3 4995.6 5109.5 3676.5 4097.7 4098.5 4110.7 4147.4 3651.5 4139.5 4146.1 4189.0 4190.7 4133.0 4134.3 4139.4 4156.8 4187.8 3901.5 3948.8 4004.0 4099.5 4128.4 3634.8 3644.3 3650.4 3659.6 3661.3 4471.9 4623.7 4727.2 4977.8 5225.3 4254.8 4500.7 4593.3 4686.7 4845.9 5612.3 5634.8 5657.8 5681.1 5681.7 4048.0 4064.9 4065.8 4077.8 4130.4 5373.1 5529.5 5825.4 5885.7 5938.3 4035.8 4391.8 4471.2 4481.4 4582.4 4360.3 4646.3 4657.8 4667.7 4690.9 4713.6 4799.9 4947.6 5021.9 5066.5 5435.9 5612.7 5634.5 5665.7 5726.0 1373.4 1429.8 1573.8 1576.8 1588.9 1000.0 1094.5 1190.9 1196.8 1197.6 4738.4 5144.4 5182.2 5195.3 5261.7 5430.9 5610.9 5687.8 5726.8 5853.8 4721.7 4770.8 4952.5 4960.2 5210.7 2949.2 2952.6 3020.8 3057.3 3057.5 5208.7 5746.7 5882.3 6112.7 6160.3 5639.4 5781.7 6010.0 6072.3 6123.8 5916.5 6009.3 6051.9 6056.7 6081.5 5617.3 5813.9 5843.3 5883.4 5889.0 4993.9 5222.0 5527.8 5542.9 5861.1 5550.5 5632.1 6058.9 6154.9 6160.8 4321.0 4544.2 4616.7 4621.8 4652.3 5493.5 5775.4 5788.4 5840.8 5849.2 4960.8 5272.6 5442.9 5644.4 5677.8 5265.7 5304.5 5531.9 5573.8 5581.7 4819.6 5314.8 5539.9 5557.7 5566.7 5507.8 5576.8 5664.0 5686.6 5692.9 5173.8 5498.5 5501.8 5540.9 5560.4 05:38:33 INFO - PROCESS | 1637 | 05:38:33 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19148.99 Median:4304.07 stddev:26200.16 (608.7%) stddev-sans-first:26237.12 05:38:33 INFO - PROCESS | 1637 | Values: 720.7 751.4 761.7 771.2 851.2 14426.6 14951.7 15004.6 17064.7 18185.3 16964.3 17117.7 17130.2 17154.0 17213.1 945.2 962.8 968.2 968.4 971.4 926.2 945.0 966.7 967.5 972.0 903.4 948.1 976.6 995.3 997.9 967.3 979.7 987.5 989.5 990.1 695.9 698.4 747.8 749.8 750.5 14622.7 14925.8 15554.0 16252.1 16537.0 13749.0 14096.9 14131.6 14138.7 14204.4 11652.9 11787.8 11797.8 11851.7 11945.9 3572.3 3622.2 3636.6 3658.4 3806.0 92142.0 95135.0 98164.5 98192.0 98585.5 10461.4 10668.1 10686.2 10701.3 10719.7 21766.0 22058.7 22348.9 22363.8 22420.6 7617.9 7687.3 7692.8 7719.3 7720.9 4160.7 4294.8 4300.8 4307.4 4382.6 3326.5 3399.8 3415.1 3422.3 3448.9 560.2 603.0 618.3 635.4 636.2 647.5 654.3 663.2 678.4 713.3 9589.0 10310.7 10353.9 10475.1 10561.5 638.4 644.9 668.3 704.1 705.2 3864.4 4567.9 4955.4 4966.9 4972.1 64910.0 98932.0 100153.5 102509.5 102991.5 59290.0 60697.4 82018.0 85969.6 89547.6 64782.7 68173.0 70639.6 71540.3 72453.0 10729.8 11092.9 11253.5 11312.4 11318.3 44512.1 45040.0 45140.5 47235.5 47554.8 52609.2 55605.7 59784.7 59941.0 60023.0 11990.8 13008.4 13360.5 13395.9 14066.5 14616.3 14689.7 15008.8 15013.5 15343.3 13531.6 13811.2 14562.6 14606.9 14735.3 12798.7 13476.8 14493.7 14776.6 15308.8 13469.0 13550.4 13679.8 13703.2 13958.2 9247.8 9897.3 9897.8 9901.9 10438.8 8905.5 10132.4 10894.9 11726.3 11775.9 05:38:33 INFO - PROCESS | 1637 | -------- Summary: end -------- 05:38:33 INFO - PROCESS | 1637 | 05:38:34 INFO - TEST-INFO | 1637: exit 0 05:38:34 INFO - TEST-OK | dromaeo_css | took 1117163ms 05:38:34 INFO - TEST-START | kraken 05:38:34 INFO - operating with platform_type : mac_ 05:38:34 INFO - Initialising browser for kraken test... 05:38:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpkSS4MQ/profile http://localhost:49240/getInfo.html) 05:38:37 INFO - PROCESS | 1650 | 2017-04-06 05:38:37.096 plugin-container[1651:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:38:37 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:37 INFO - PROCESS | 1650 | 2017-04-06 05:38:37.102 plugin-container[1651:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 05:38:37 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:37 INFO - PROCESS | 1650 | 2017-04-06 05:38:37.102 plugin-container[1651:10247] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:37 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:38:37 INFO - PROCESS | 1650 | colorDepth:24 05:38:37 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:38:37 INFO - PROCESS | 1650 | __metrics 05:38:37 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 05:38:38 INFO - TEST-INFO | 1650: exit 0 05:38:38 INFO - Browser initialized. 05:38:38 INFO - Running cycle 1/1 for kraken test... 05:38:38 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/T/tmpkSS4MQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:40 INFO - PROCESS | 1652 | 2017-04-06 05:38:40.549 plugin-container[1653:10371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:38:40 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:40 INFO - PROCESS | 1652 | 2017-04-06 05:38:40.549 plugin-container[1653:10371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.CFPasteboardClient' 05:38:40 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:38:40 INFO - PROCESS | 1652 | 2017-04-06 05:38:40.549 plugin-container[1653:10371] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:38:50 INFO - PROCESS | 1652 | RSS: Main: 221007872 05:38:50 INFO - PROCESS | 1652 | 05:38:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:54 INFO - PROCESS | 1652 | RSS: Main: 235548672 05:38:54 INFO - PROCESS | 1652 | 05:38:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 05:38:57 INFO - PROCESS | 1652 | RSS: Main: 243634176 05:38:57 INFO - PROCESS | 1652 | 05:39:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 05:39:01 INFO - PROCESS | 1652 | RSS: Main: 247312384 05:39:01 INFO - PROCESS | 1652 | 05:39:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 05:39:03 INFO - PROCESS | 1652 | RSS: Main: 247656448 05:39:03 INFO - PROCESS | 1652 | 05:39:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:06 INFO - PROCESS | 1652 | RSS: Main: 247705600 05:39:06 INFO - PROCESS | 1652 | 05:39:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:10 INFO - PROCESS | 1652 | RSS: Main: 251383808 05:39:10 INFO - PROCESS | 1652 | 05:39:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:14 INFO - PROCESS | 1652 | RSS: Main: 252596224 05:39:14 INFO - PROCESS | 1652 | 05:39:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 05:39:17 INFO - PROCESS | 1652 | RSS: Main: 251998208 05:39:17 INFO - PROCESS | 1652 | 05:39:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:20 INFO - PROCESS | 1652 | RSS: Main: 251932672 05:39:20 INFO - PROCESS | 1652 | 05:39:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:22 INFO - PROCESS | 1652 | RSS: Main: 251875328 05:39:22 INFO - PROCESS | 1652 | 05:39:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:25 INFO - PROCESS | 1652 | RSS: Main: 251936768 05:39:25 INFO - PROCESS | 1652 | 05:39:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:29 INFO - PROCESS | 1652 | RSS: Main: 252354560 05:39:29 INFO - PROCESS | 1652 | 05:39:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:32 INFO - PROCESS | 1652 | RSS: Main: 252170240 05:39:32 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 05:39:34 INFO - PROCESS | 1652 | RSS: Main: 254078976 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | __start_tp_report 05:39:34 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:39:34 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:39:34 INFO - PROCESS | 1652 | |i|pagename|runs| 05:39:34 INFO - PROCESS | 1652 | |0;ai-astar;103;119;119;120;119;118;121;120;132;177 05:39:34 INFO - PROCESS | 1652 | |1;audio-beat-detection;169;144;142;147;112;115;142;113;132;112 05:39:34 INFO - PROCESS | 1652 | |2;audio-dft;201;200;201;200;199;196;198;196;200;197 05:39:34 INFO - PROCESS | 1652 | |3;audio-fft;85;61;60;88;61;61;62;60;71;54 05:39:34 INFO - PROCESS | 1652 | |4;audio-oscillator;98;97;117;72;72;71;82;72;70;82 05:39:34 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;94;90;88;92;87;92;88;92;87 05:39:34 INFO - PROCESS | 1652 | |6;imaging-darkroom;153;95;95;128;152;131;96;142;155;120 05:39:34 INFO - PROCESS | 1652 | |7;imaging-desaturate;92;91;91;94;147;90;91;90;90;96 05:39:34 INFO - PROCESS | 1652 | |8;json-parse-financial;67;64;65;66;67;68;68;69;68;70 05:39:34 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;55;63;63;55;55;60;78;82;81;59 05:39:34 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;109;106;104;80;87;79;92;87;111;98 05:39:34 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;139;139;139;137;140;148;145;145;182;147 05:39:34 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;169;171;167;164;169;162;168;165;165;184 05:39:34 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;66;67;67;67;68;63;61;85;84 05:39:34 INFO - PROCESS | 1652 | __end_tp_report 05:39:34 INFO - PROCESS | 1652 | __start_cc_report 05:39:34 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1030 05:39:34 INFO - PROCESS | 1652 | __end_cc_report 05:39:34 INFO - PROCESS | 1652 | __startTimestamp1491482374746__endTimestamp 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:39:34 INFO - PROCESS | 1652 | Number of tests: 14 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:124.80 Median:120.00 stddev:19.61 (16.3%) stddev-sans-first:19.14 05:39:34 INFO - PROCESS | 1652 | Values: 103.0 119.0 119.0 120.0 119.0 118.0 121.0 120.0 132.0 177.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:142.00 stddev:19.38 (13.7%) stddev-sans-first:15.51 05:39:34 INFO - PROCESS | 1652 | Values: 169.0 144.0 142.0 147.0 112.0 115.0 142.0 113.0 132.0 112.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:198.80 Median:200.00 stddev:1.93 (1.0%) stddev-sans-first:1.88 05:39:34 INFO - PROCESS | 1652 | Values: 201.0 200.0 201.0 200.0 199.0 196.0 198.0 196.0 200.0 197.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.30 Median:61.50 stddev:11.43 (18.6%) stddev-sans-first:9.92 05:39:34 INFO - PROCESS | 1652 | Values: 85.0 61.0 60.0 88.0 61.0 61.0 62.0 60.0 71.0 54.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:77.00 stddev:15.83 (20.6%) stddev-sans-first:15.87 05:39:34 INFO - PROCESS | 1652 | Values: 98.0 97.0 117.0 72.0 72.0 71.0 82.0 72.0 70.0 82.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:2.53 (2.8%) stddev-sans-first:2.60 05:39:34 INFO - PROCESS | 1652 | Values: 88.0 94.0 90.0 88.0 92.0 87.0 92.0 88.0 92.0 87.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:126.70 Median:154.00 stddev:24.46 (15.9%) stddev-sans-first:24.02 05:39:34 INFO - PROCESS | 1652 | Values: 153.0 95.0 95.0 128.0 152.0 131.0 96.0 142.0 155.0 120.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:91.00 stddev:17.61 (19.3%) stddev-sans-first:18.57 05:39:34 INFO - PROCESS | 1652 | Values: 92.0 91.0 91.0 94.0 147.0 90.0 91.0 90.0 90.0 96.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:68.00 stddev:1.81 (2.7%) stddev-sans-first:1.92 05:39:34 INFO - PROCESS | 1652 | Values: 67.0 64.0 65.0 66.0 67.0 68.0 68.0 69.0 68.0 70.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:63.00 stddev:10.97 (17.4%) stddev-sans-first:11.01 05:39:34 INFO - PROCESS | 1652 | Values: 55.0 63.0 63.0 55.0 55.0 60.0 78.0 82.0 81.0 59.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:83.50 stddev:11.93 (14.3%) stddev-sans-first:11.57 05:39:34 INFO - PROCESS | 1652 | Values: 109.0 106.0 104.0 80.0 87.0 79.0 92.0 87.0 111.0 98.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:145.00 stddev:13.19 (9.1%) stddev-sans-first:13.74 05:39:34 INFO - PROCESS | 1652 | Values: 139.0 139.0 139.0 137.0 140.0 148.0 145.0 145.0 182.0 147.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:168.50 stddev:6.11 (3.6%) stddev-sans-first:6.48 05:39:34 INFO - PROCESS | 1652 | Values: 169.0 171.0 167.0 164.0 169.0 162.0 168.0 165.0 165.0 184.0 05:39:34 INFO - PROCESS | 1652 | 05:39:34 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.00 stddev:8.29 (12.4%) stddev-sans-first:8.64 05:39:34 INFO - PROCESS | 1652 | Values: 65.0 66.0 67.0 67.0 67.0 68.0 63.0 61.0 85.0 84.0 05:39:34 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:39:34 INFO - PROCESS | 1652 | 05:39:35 INFO - TEST-INFO | 1652: exit 0 05:39:35 INFO - TEST-OK | kraken | took 61524ms 05:39:35 INFO - SUITE-END | took 1178s 05:39:35 INFO - Completed test suite (00:19:38) 05:39:35 INFO - javascript benchmark 05:39:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7597.166849403412, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.667506574275, 3469.358168738783, 3483.233688588511, 3489.860405583777, 3512.877049303275, 5011.523905228997, 5326.748214169676, 6012.308584512777, 6017.578769486137, 6056.818295451132, 6034.487068599164, 6056.606320589159, 6092.87814243716, 6112.327643959172, 6117.082437634357, 5435.891282174354, 5440.292761940962, 5533.750981205855, 5575.386707462176, 5603.915941260864, 4701.7414042227665, 5459.863503412426, 5489.39616642169, 5588.4411558844195, 5591.329040515144, 5241.977814326208, 5298.470152984709, 5343.625946183769, 5477.233187353793, 5489.670619762827, 4207.873763787091, 4898.608111351084, 5365.517103460707, 5378.273933019071, 5414.431484694129, 5388.703621300827, 5397.865053373697, 5409.2967914171295, 5452.0, 5464.207689884985, 5150.686574923408, 5183.922241166386, 5972.134040564094, 6057.000594901836, 6134.539909506805, 6203.4416902479, 6238.407351301619, 6381.93618063818, 6391.105245265667, 6399.520035997319, 6348.682565871688, 6402.775902843425, 6410.358964103599, 6420.5184611154355, 6428.067930150149, 6182.010878259547, 6195.0707393890825, 6198.380161983856, 6200.038993955944, 6219.595726277823, 6474.546781725328, 6612.173478315211, 6616.603003819786, 6644.368784965421, 6649.268580456146, 6209.627422354673, 6216.720247588779, 6234.407731265523, 6253.624782512973, 6304.3695630437505, 6461.127747754087, 6468.288488266287, 6468.514861385415, 6481.546291759531, 6482.740690372332, 6263.279722831841, 6353.745850166034, 6361.300256971729, 6395.104685344056, 6397.552171348054, 5032.874178145576, 6252.781152659726, 6262.780802671976, 6274.372562743689, 6296.275928268216, 5149.9742501287255, 5155.355580552431, 5160.612954028463, 5171.7155556444, 5199.896002079937, 4492.168948744493, 4604.125216208977, 4608.124456353282, 4621.861344159681, 4646.465656449552, 4972.900541989212, 5873.236479257669, 5891.49922256613, 5951.553633477506, 6042.939570604326, 6440.291567927524, 6443.67781610927, 6444.581102228341, 6454.806355809334, 6461.0631458438265, 5990.850228744316, 6039.969800150971, 6047.8488037798525, 6054.212952316258, 6064.787732429344, 5452.100403433389, 5542.196381524737, 5553.222548843165, 5609.0, 5656.830295091154, 5932.673702946379, 6738.157730283812, 6752.763653272112, 6755.729770809113, 6769.932300677127, 6183.258009038944, 6403.423691867754, 6411.294757576569, 6413.422791948747, 6433.517486188461, 6304.621722696653, 6701.296363881722, 6706.0, 6761.0, 6777.525573210025, 4733.502982186821, 4934.679245849008, 5500.889982200253, 5533.280673512418, 6260.5930614511735, 6335.936640633719, 6349.682515874096, 6379.106925030406, 6386.457151142019, 6461.838454038687, 6131.0803379491645, 6250.249970003629, 6280.183576135073, 6283.874322513433, 6336.746530138743, 6121.666376154749, 6156.107364432221, 6174.413430724074, 6223.533235007302, 6245.344238855037, 5321.840344789663, 6073.635581865102, 6168.228971378578, 6212.81361559154, 6248.250209974651, 6242.001279795211, 6256.0, 6273.811785646438, 6286.811395658137, 6289.119523266838, 6071.210742603434, 6124.38756124384, 6185.8144255672405, 6193.84515387119, 6259.561830671809], "value": 5835.253970553562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.8308380614362, 810.5987536169595, 825.4057078903191, 826.9710560130396, 955.6225291010055, 38849.80575097124, 39663.80168099163, 39690.0, 39734.0, 39824.800875995614, 38268.0, 38472.61527384725, 38489.037774055665, 38538.65114720985, 38590.0, 7768.990031295938, 7955.124936257007, 10319.0, 10591.576336946513, 10599.20505962052, 10272.229582781283, 10467.371957682526, 10469.31949423285, 10476.319039262424, 10486.632967846126, 10451.58193672256, 10499.42253176075, 10515.579376824919, 10525.157987361032, 10532.526036328383, 10427.270091093627, 10443.791124177513, 10460.215483838743, 10466.895331046668, 10486.528106235199, 8808.427452215585, 10187.134093602053, 10272.33229840058, 10280.537375818032, 10316.432596207205, 29645.555316670267, 31647.367052659047, 33448.331033379436, 33523.32953340944, 33545.6645433545, 3402.0814380117477, 3719.237556300952, 3743.4777634635507, 3789.810509474543, 3792.6207379261855, 24540.386490337885, 24906.12828550992, 25190.874045629655, 25336.239912802645, 26203.344916377053, 2644.2331723800075, 3056.037348235299, 3076.384723055398, 3119.2201949512623, 3144.811311321328, 401942.9999999971, 403005.0, 447238.99999999674, 464571.0, 467509.0, 35619.0, 42931.9267018327, 43163.78418107952, 43223.0, 43280.0, 45309.546904530536, 45476.54523454723, 45562.0, 45645.771771140935, 45689.31466028012, 13176.60470185896, 13279.867201327863, 13291.3354332283, 13306.800397994039, 13442.529511467052, 9395.765105872271, 11333.376664283544, 11515.078793696652, 11517.078633709283, 11595.594154204731, 10445.11216546587, 11747.941260293645, 11763.705907352385, 11781.646550603497, 11876.821847672292, 5181.6631918925905, 5468.671879687232, 5474.589405794582, 5478.616496845262, 5488.17677348401, 1119.2780656476527, 1138.135017386792, 1142.5486932661552, 1159.646307876089, 1187.1511869013696, 26177.607335889978, 26333.868330658224, 26500.337491562866, 26531.336716581853, 26535.93856245767, 1140.1733743036398, 1241.4227384266178, 1241.5344245907784, 1257.8050402187675, 1257.9433925473375, 13546.864531354757, 13591.592252232449, 13781.655458613413, 13806.964477664014, 13839.377228024763, 396265.0, 397532.0, 397722.0, 397811.0, 399782.0, 53767.462325377026, 53856.92286154333, 53956.0, 54190.91618167615, 54610.72694636581, 1381.7927310903444, 1392.3525560614362, 1415.4288739499148, 1422.73679369317, 1424.0245431879196, 24962.875185623954, 27541.586876197274, 27726.861365693043, 27796.166115016582, 27872.44255114927, 33955.66044339525, 41002.79498602488, 41236.38145427881, 41267.58732412758, 41302.0, 410128.0, 448628.0, 460884.69557652, 461487.0, 463208.6839565781, 25488.8530016146, 29615.703842961295, 29893.70106298909, 29923.55114673238, 30140.54789178208, 38274.2345153101, 39110.0, 39284.0, 39440.0, 39456.2108757829, 25986.48027039411, 28276.575851361835, 28394.148175554765, 28692.85228590833, 28713.995010174545, 27850.860745696144, 28149.71850281553, 28265.858670706515, 28302.0, 29367.55948660729, 30040.0, 32380.83809580937, 32921.01236962895, 32966.0, 33150.005499835046, 23009.539809204056, 25002.624960625242, 27955.440891181657, 28140.85929570339, 28980.565291520223, 19315.130819112892, 21111.8944405277, 21832.126714931226, 23018.654720178874, 23110.30669079863], "value": 17872.388490077537, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.44595228822783, 417.85375118708436, 419.5971867007672, 435.6057767720212, 462.27653721925185, 18123.365682201125, 19608.215671373127, 19750.407487775356, 20114.597708045847, 20949.68575471367, 20897.582048359025, 21195.788042119573, 21240.0, 21981.010854511544, 22091.889540552274, 6269.561130720851, 6382.680865956707, 6408.038794180864, 6424.71088801005, 6433.710483028275, 6333.778317758879, 6811.284815094418, 7150.5352152109945, 7271.89092163618, 7275.1633562140505, 7023.61370124643, 7173.354398104169, 7205.279472052805, 7245.89131163033, 7282.672279747398, 7101.076860008166, 7136.500444968854, 7178.2103968563415, 7205.819854503627, 7215.891761623579, 6603.405693487609, 6958.8956165657555, 7053.365197132282, 7062.646867656596, 7068.0, 23068.76931230683, 24766.25701228966, 25308.49383012348, 25442.745572544223, 25498.0, 2772.778177745796, 2885.6104425902445, 2886.235147685865, 2892.2335581071034, 2915.6646985596717, 15564.132393986467, 15997.360105595762, 16184.676306473804, 16233.51299461018, 16667.83325167239, 2063.2778527515397, 2089.8119169274687, 2099.170827523135, 2108.7785782492924, 2140.2616097446357, 91188.63217051749, 107382.0, 135112.0, 139231.0, 149958.25020874935, 15152.469663561722, 16230.837691622932, 16569.585760356087, 20488.824232244027, 20601.58796824055, 31099.84450077735, 31154.909578164654, 31187.220319491742, 31367.0, 31367.529487057713, 10136.037076477724, 10182.847257291147, 10242.48787560619, 10382.636607718845, 10465.790684186273, 8499.70251041223, 8512.70205542803, 8527.062023177445, 8637.0, 8666.696665616673, 9397.201237894855, 9417.90582094184, 9585.760355991157, 9588.424694518351, 9602.991685872883, 4138.6482149017675, 4228.746275223496, 4258.87223383299, 4278.593533614302, 4410.448693913261, 997.5162046407487, 1007.9042490963346, 1015.8882522922472, 1017.9287449878437, 1049.6955882793904, 16890.74663880043, 20503.4874128148, 20514.897425512776, 20537.89731051365, 20578.38264852057, 1005.7234260578253, 1010.6412223660642, 1033.3180101133216, 1039.1686650679426, 1040.9323393979366, 10003.79992400148, 10272.280940334254, 10347.534360953776, 10377.325473844176, 10378.896211037792, 721558.0, 740469.0, 742479.0, 753871.0, 756633.0, 120768.39615801864, 141291.0, 142180.0, 148265.0, 149042.0, 1103.6027030269097, 1109.3953795181485, 1178.5403692559908, 1213.3326670331282, 1259.2444533279959, 14999.575040371148, 17630.82369176292, 19737.80262197417, 19806.0, 19806.801931980495, 57759.0, 58353.124703130336, 58361.0, 58400.70799645974, 60420.69789651024, 70458.94311585433, 80264.0, 81766.18233817823, 82158.58920705598, 83628.58185709032, 9037.77405564866, 10448.947755261175, 10635.096016838655, 10795.0, 11243.66269011931, 11601.883981160418, 11782.705432364259, 12106.515739370327, 12135.150539462153, 12174.634760957186, 9891.109800118023, 9924.950375248078, 10010.249231307535, 10036.447995360326, 10261.230407719302, 9573.856392153984, 9734.853977190207, 9790.06994335537, 9803.0, 9839.01609839039, 10288.074073333435, 10326.845097323696, 10403.479826008821, 10451.634192803216, 10619.36283822973, 8284.917150828414, 10690.877457867124, 10797.460126993776, 11036.889631103908, 11069.83395249088, 10038.247131465023, 10112.292139550455, 10333.689989300334, 10366.740831479274, 10379.688609341732], "value": 11159.342004995018, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1523.7638166084248, 1566.6631674190055, 1573.2920185916341, 1586.4447443394813, 1591.816941051779, 10115.494225288734, 11119.666410007712, 11200.327980321175, 11206.991370776628, 11415.31508109514, 11005.119590432767, 11142.387168705718, 11192.048675862541, 11204.551817927293, 11234.662960111189, 2432.793212576933, 2474.2206205045354, 2474.7772700456953, 2478.281298423455, 2480.0327872129883, 2414.1550457339877, 2425.987870060656, 2470.209532949457, 2471.0115953618515, 2473.468204336067, 2425.4785221177444, 2426.3085020769086, 2443.5235129149823, 2463.08865719684, 2474.950500989979, 2087.332053742803, 2251.324602619208, 2434.610462326028, 2442.535918175541, 2449.216250799745, 2137.925172618951, 2414.625733011386, 2428.987855060713, 2448.7796098351055, 2449.326435230308, 10644.627438039632, 10703.250772445932, 10714.678559643224, 10801.189910756646, 10812.026917577377, 5491.28613280275, 5627.324721033502, 5650.660960342392, 5674.460926212003, 5687.146927960797, 3106.6427360853563, 3141.8743250269963, 3157.321175947159, 3750.587435382106, 3772.8868133956025, 1200.5497938273147, 1202.3988005997, 1238.0467040378949, 1257.5535684831843, 1259.0745801835644, 13816.93091534536, 14018.228997405138, 14338.713225735426, 14461.855381446261, 14761.483348082873, 4704.600108990706, 4748.287756836502, 4748.928766068511, 4868.659193856467, 4903.53416425439, 11534.077273818075, 11597.304161750322, 11642.6507204784, 11658.00906922921, 11666.300021998708, 4957.429895562056, 5797.246357973437, 5891.351951285355, 5893.5579831512805, 5895.380984996514, 4404.405405270311, 5263.868403289872, 5279.049771041249, 5356.973215133899, 5400.702961337086, 3913.1977944521304, 3983.980080099581, 4008.959910400917, 4057.8173982170874, 4069.064115253508, 1076.956921723138, 1077.3320541264466, 1083.7453198498347, 1092.415557676655, 1095.3756358875523, 918.7703074231442, 936.180841763457, 950.2493030505962, 955.861400096982, 996.7607774134301, 6457.870842583122, 6481.449076828428, 6602.2737498875085, 6695.4308883745425, 6770.344951419464, 957.066859811678, 983.1643103362085, 1001.5392919257077, 1009.1422291052693, 1027.9383237005804, 4164.250434921743, 4201.6008479194425, 4209.7474151551005, 4298.613124818781, 4316.676249281317, 13333.733325333633, 13952.651183720285, 13974.882009439221, 13989.930050349887, 14027.719445611236, 12923.483060677656, 13428.597142085753, 13761.793573096411, 13882.861171388156, 13904.513342033182, 8473.576321183793, 9984.650537231162, 10272.743181420525, 10275.948620256851, 10295.07344339013, 5791.53667706574, 5938.61399009063, 6770.086038384952, 6905.75829845953, 6910.136232970879, 12305.692357691129, 12438.875611243771, 12445.937770311091, 12492.37538123072, 12516.061295403064, 12505.0, 12537.373131343578, 12560.497580096693, 12798.16811907223, 12880.35598220104, 9172.266218702354, 9245.491497967676, 9282.72151835446, 9368.437893726126, 9410.717678469657, 10191.0, 10218.284720069822, 10258.43578603184, 10316.535755890853, 10338.431386273827, 8738.563071846509, 8762.474251544927, 8766.342524310574, 8807.647694092164, 8809.898762654668, 8479.95760021196, 8527.0, 8595.226429621363, 8644.91355086441, 8752.51861147643, 7846.686132554634, 7857.764267071997, 7867.685292588232, 7958.442908996546, 7988.081370642218, 5755.251817263729, 5886.499647530008, 5911.231539899785, 5919.644821310563, 5953.910691339547, 4889.437714662859, 5225.608079393985, 5855.473007429352, 5904.468597826216, 6085.421884920926], "value": 5134.596572099519, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.4304621544575, 389.38865980410765, 393.67915149153447, 395.9901002474938, 396.9238402381544, 4584.610308123813, 4775.230366387495, 4916.337328690126, 4936.851894443168, 5206.43806857942, 4282.464691913511, 4410.558944105588, 4685.344051832746, 4995.5753760930365, 5109.540141387274, 3676.522052133219, 4097.713160078795, 4098.549159592442, 4110.7122501424765, 4147.398627199054, 3651.4522821576843, 4139.482564679415, 4146.108586653869, 4188.958110418903, 4190.664746820263, 4133.049398638315, 4134.276501612221, 4139.379093136024, 4156.7945295864165, 4187.796335803113, 3901.4691704007637, 3948.8420463181583, 4004.0, 4099.549049604541, 4128.401381799622, 3634.818259087035, 3644.2711457708565, 3650.4159334506617, 3659.5608526976666, 3661.3226553087497, 4471.888202794924, 4623.653226008063, 4727.203662608221, 4977.7511124444, 5225.268462415265, 4254.765987870666, 4500.707454015478, 4593.27944365062, 4686.656671664168, 4845.892036963932, 5612.270404847384, 5634.830955071355, 5657.830265092054, 5681.147827825818, 5681.659100453986, 4048.0164719287336, 4064.878053658395, 4065.7560546367313, 4077.7757223352705, 4130.442390277304, 5373.086575282172, 5529.529989950859, 5825.388334224887, 5885.704575215669, 5938.257717785277, 4035.8183881724804, 4391.824327026947, 4471.1728330259, 4481.3726078349055, 4582.350353872604, 4360.280553708666, 4646.326282689059, 4657.790399431966, 4667.6732628716345, 4690.882727931761, 4713.622910167179, 4799.904001920012, 4947.6108865427295, 5021.949780502221, 5066.493350664978, 5435.901536124371, 5612.719364031782, 5634.464725851038, 5665.716714164275, 5726.0, 1373.4368908747365, 1429.8355689095679, 1573.7796708460824, 1576.8107827060826, 1588.864946479539, 999.9850002249972, 1094.4746521669642, 1190.922590031645, 1196.760647870412, 1197.5569039455456, 4738.431388233434, 5144.38267407906, 5182.170852663556, 5195.3115237547945, 5261.657992230569, 5430.91853622196, 5610.887782244332, 5687.805560832189, 5726.77092916287, 5853.795117170964, 4721.693089949211, 4770.76146192689, 4952.455229924705, 4960.156773348539, 5210.687358758487, 2949.2037149969815, 2952.5571164325524, 3020.776585482883, 3057.2968217310145, 3057.5107982722657, 5208.661437006583, 5746.684317006752, 5882.294124704978, 6112.724927378279, 6160.291566469824, 5639.416977192632, 5781.739821708034, 6010.0083486224285, 6072.332043475214, 6123.755049797958, 5916.473897468354, 6009.338972712998, 6051.88184135549, 6056.727447264883, 6081.452669259788, 5617.269754931833, 5813.912791308049, 5843.328017278068, 5883.411658834082, 5889.0, 4993.925091123563, 5222.0, 5527.806526771705, 5542.944570554404, 5861.0915308127305, 5550.5282051026115, 5632.127020311858, 6058.878822423439, 6154.907676384947, 6160.815175544741, 4321.027768752006, 4544.22775117711, 4616.746078965689, 4621.83823566187, 4652.32541281519, 5493.533049690821, 5775.422457754359, 5788.363280039108, 5840.766369345349, 5849.151872978479, 4960.751962401793, 5272.578193744414, 5442.863928401822, 5644.407337229532, 5677.801276955287, 5265.6840589564745, 5304.549113325411, 5531.861703457284, 5573.832785016456, 5581.665100094007, 4819.638527110551, 5314.7874085036165, 5539.944600553943, 5557.666540007451, 5566.721663916869, 5507.807226747205, 5576.769292283119, 5663.9716801415725, 5686.630369026, 5692.914606280826, 5173.793048278178, 5498.477644623755, 5501.771782428123, 5540.944590554042, 5560.443955604407], "value": 4275.581121591494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.7405334079727, 751.3876190904413, 761.7295859969707, 771.2249189564487, 851.2462184832067, 14426.567202983913, 14951.700965980688, 15004.624884377883, 17064.658706825856, 18185.272589096454, 16964.3214271429, 17117.657646847056, 17130.229139688712, 17153.970761754303, 17213.139343032828, 945.2060269373742, 962.7881865989506, 968.1818863060703, 968.4141094637748, 971.3928794503436, 926.2358554192784, 945.0266225787416, 966.7099870038995, 967.5307475874196, 971.9902800972006, 903.4488961733338, 948.0993056596227, 976.623999760094, 995.258532393368, 997.9201663866874, 967.2938754709102, 979.7354714227155, 987.476637382185, 989.5101924547323, 990.074280547683, 695.8782213112685, 698.4203111417511, 747.8130467383154, 749.7750674797594, 750.5271678842349, 14622.707545849129, 14925.776113358308, 15554.0, 16252.106134162615, 16537.0, 13749.0, 14096.859031409656, 14131.646708832259, 14138.71722565543, 14204.360803763855, 11652.94173529127, 11787.823182652268, 11797.823032654516, 11851.703707407383, 11945.940270298679, 3572.3034008368377, 3622.2212224371888, 3636.581793093775, 3658.359787037258, 3806.0484878780303, 92142.0, 95135.0, 98164.50917745366, 98192.018079819, 98585.5070724649, 10461.372317660966, 10668.07446734326, 10686.198535109743, 10701.304415212986, 10719.678409647722, 21766.0, 22058.669119963215, 22348.888255558617, 22363.77636223617, 22420.55158896813, 7617.8954051662195, 7687.346575541142, 7692.7692169235015, 7719.266669666373, 7720.922790772021, 4160.667146628262, 4294.806733696991, 4300.7634580097765, 4307.375430562551, 4382.649388048949, 3326.4843949187916, 3399.847006884696, 3415.112070861594, 3422.3155368926314, 3448.8963531669624, 560.2268868960889, 602.9969850150809, 618.2519151826341, 635.4154178156108, 636.1761518833122, 647.498188903594, 654.3489228217859, 663.2074670768482, 678.4063944048958, 713.3437237741186, 9588.952055239819, 10310.693713501216, 10353.896461035294, 10475.1096156826, 10561.471926403648, 638.396715104222, 644.8682562103478, 668.3483603486561, 704.1198501872659, 705.1714235772946, 3864.42033694948, 4567.862964111082, 4955.40535135786, 4966.875828104254, 4972.105021096165, 64910.0, 98932.0, 100153.49923250337, 102509.48745256376, 102991.4850425743, 59290.0, 60697.39302607006, 82018.0, 85969.57015214884, 89547.55226223958, 64782.704345913764, 68173.0, 70639.64680176566, 71540.2845971544, 72453.0, 10729.839052414376, 11092.944535277595, 11253.549858005916, 11312.377819220023, 11318.32090074598, 44512.10975780531, 45040.0, 45140.54859451363, 47235.527644723115, 47554.76222618865, 52609.210861836335, 55605.72197138988, 59784.70107649434, 59941.0, 60023.0, 11990.760184796429, 13008.414621342252, 13360.532381366605, 13395.933020334836, 14066.507672231832, 14616.269186540843, 14689.659292299659, 15008.774868376764, 15013.474528391454, 15343.309551070452, 13531.557916160014, 13811.240381779098, 14562.563123106323, 14606.926965365104, 14735.2632368379, 12798.68003299925, 13476.797848032484, 14493.710125797214, 14776.63058423548, 15308.846911530742, 13468.98982576291, 13550.390232439368, 13679.79480307816, 13703.246321452018, 13958.23229722375, 9247.80784602217, 9897.307188497025, 9897.802043959224, 9901.851472227778, 10438.843417348595, 8905.465672059698, 10132.405355700079, 10894.891051089387, 11726.296422214695, 11775.882241177478], "value": 7524.697549343255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 119.0, 119.0, 120.0, 119.0, 118.0, 121.0, 120.0, 132.0, 177.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 144.0, 142.0, 147.0, 112.0, 115.0, 142.0, 113.0, 132.0, 112.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 200.0, 201.0, 200.0, 199.0, 196.0, 198.0, 196.0, 200.0, 197.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 60.0, 88.0, 61.0, 61.0, 62.0, 60.0, 71.0, 54.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 117.0, 72.0, 72.0, 71.0, 82.0, 72.0, 70.0, 82.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 90.0, 88.0, 92.0, 87.0, 92.0, 88.0, 92.0, 87.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 95.0, 95.0, 128.0, 152.0, 131.0, 96.0, 142.0, 155.0, 120.0], "value": 126.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 91.0, 94.0, 147.0, 90.0, 91.0, 90.0, 90.0, 96.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 66.0, 67.0, 68.0, 68.0, 69.0, 68.0, 70.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 63.0, 55.0, 55.0, 60.0, 78.0, 82.0, 81.0, 59.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 104.0, 80.0, 87.0, 79.0, 92.0, 87.0, 111.0, 98.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 139.0, 137.0, 140.0, 148.0, 145.0, 145.0, 182.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 167.0, 164.0, 169.0, 162.0, 168.0, 165.0, 165.0, 184.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 67.0, 68.0, 63.0, 61.0, 85.0, 84.0], "value": 69.3, "unit": "score"}]}]} 05:39:35 INFO - Return code: 0 05:39:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:39:36 ERROR - # TBPL SUCCESS # 05:39:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:36 INFO - 2017-04-06 05:39:36.057 screenresolution[1657:10747] starting screenresolution argv=screenresolution get 05:39:36 INFO - 2017-04-06 05:39:36.059 screenresolution[1657:10747] Display 0: 1600x1200x32@60 05:39:36 INFO - 2017-04-06 05:39:36.066 screenresolution[1658:10750] starting screenresolution argv=screenresolution list 05:39:36 INFO - Available Modes on Display 0 05:39:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:36 INFO - Intel Iris: 05:39:36 INFO - Chipset Model: Intel Iris 05:39:36 INFO - Type: GPU 05:39:36 INFO - Bus: Built-In 05:39:36 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:36 INFO - Vendor: Intel (0x8086) 05:39:36 INFO - Device ID: 0x0a2e 05:39:36 INFO - Revision ID: 0x0009 05:39:36 INFO - Displays: 05:39:36 INFO - Display: 05:39:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:36 INFO - Display Serial Number: 1600x1200 60 05:39:36 INFO - Main Display: Yes 05:39:36 INFO - Mirror: Off 05:39:36 INFO - Online: Yes 05:39:36 INFO - Rotation: Supported 05:39:36 INFO - Return code: 0 05:39:36 INFO - Running post-action listener: _resource_record_post_action 05:39:36 INFO - [mozharness: 2017-04-06 12:39:36.303778Z] Finished run-tests step (success) 05:39:36 INFO - Running post-run listener: _resource_record_post_run 05:39:36 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 40274432; Write bytes: 514847744; Read time: 392; Write time: 1676 05:39:36 INFO - TinderboxPrint: CPU usage
54.9% 05:39:36 INFO - TinderboxPrint: I/O read bytes / time
40,274,432 / 392 05:39:36 INFO - TinderboxPrint: I/O write bytes / time
514,847,744 / 1,676 05:39:36 INFO - TinderboxPrint: CPU idle
2,163.5 (45.1%) 05:39:36 INFO - TinderboxPrint: CPU system
967.7 (20.2%) 05:39:36 INFO - TinderboxPrint: CPU user
1,666.6 (34.7%) 05:39:36 INFO - TinderboxPrint: Swap in / out
248,422,400 / 0 05:39:36 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208083456; Write bytes: 201615360; Read time: 15895; Write time: 421 05:39:36 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35400192; Write bytes: 303057920; Read time: 356; Write time: 1219 05:39:36 INFO - Running post-run listener: _upload_blobber_files 05:39:36 INFO - Blob upload gear active. 05:39:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:36 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 05:39:36 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'] 05:39:36 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 05:39:37 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:39:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:39:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:37 INFO - (blobuploader) - INFO - Done attempting. 05:39:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:39:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:39:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:38 INFO - (blobuploader) - INFO - Done attempting. 05:39:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:39:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:39:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:39 INFO - (blobuploader) - INFO - Done attempting. 05:39:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:39:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:39:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:42 INFO - (blobuploader) - INFO - Done attempting. 05:39:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:42 INFO - Return code: 0 05:39:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c09bb537ffd30c56f54dfeca8c9d3882637ffb2936899d77fe1829355fa97c74580e4c5f0f64303701caf36a226e79e60b30b5fd437e3796115cc7d90c93fa9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54cfb6ae3a9eb7ff5f9ac8525b0f3f3753d59473391fe0895ad4f0208aeaa4d18124248aa62d14f4c23c4a30102a9f6bb5b068b21ea7cc3e6afd972283626d89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5521fc82e556ae7a718810ae903a3ea1109adb236913ff914be930dbab335cd758f6175a63120577bf6e1000984b0105c1bb33799460a3bb0a55c7fd8badd861", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/167df98b6415123b186772938fc791a42c143ebac776f67866a4c3cec36e0ad1d97bae09f8c53d216e8ec51b949ad034a1d9a53c0f4c5cb458b8388419d213d8"} 05:39:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:42 INFO - Contents: 05:39:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c09bb537ffd30c56f54dfeca8c9d3882637ffb2936899d77fe1829355fa97c74580e4c5f0f64303701caf36a226e79e60b30b5fd437e3796115cc7d90c93fa9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54cfb6ae3a9eb7ff5f9ac8525b0f3f3753d59473391fe0895ad4f0208aeaa4d18124248aa62d14f4c23c4a30102a9f6bb5b068b21ea7cc3e6afd972283626d89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5521fc82e556ae7a718810ae903a3ea1109adb236913ff914be930dbab335cd758f6175a63120577bf6e1000984b0105c1bb33799460a3bb0a55c7fd8badd861", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/167df98b6415123b186772938fc791a42c143ebac776f67866a4c3cec36e0ad1d97bae09f8c53d216e8ec51b949ad034a1d9a53c0f4c5cb458b8388419d213d8"} 05:39:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:42 INFO - Copying logs to upload dir... 05:39:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.984195 ========= master_lag: 4.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-04-06 05:39:46.813012) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:39:46.818943) ========= 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.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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/c09bb537ffd30c56f54dfeca8c9d3882637ffb2936899d77fe1829355fa97c74580e4c5f0f64303701caf36a226e79e60b30b5fd437e3796115cc7d90c93fa9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54cfb6ae3a9eb7ff5f9ac8525b0f3f3753d59473391fe0895ad4f0208aeaa4d18124248aa62d14f4c23c4a30102a9f6bb5b068b21ea7cc3e6afd972283626d89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5521fc82e556ae7a718810ae903a3ea1109adb236913ff914be930dbab335cd758f6175a63120577bf6e1000984b0105c1bb33799460a3bb0a55c7fd8badd861", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/167df98b6415123b186772938fc791a42c143ebac776f67866a4c3cec36e0ad1d97bae09f8c53d216e8ec51b949ad034a1d9a53c0f4c5cb458b8388419d213d8"} build_url:https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011600 build_url: 'https://queue.taskcluster.net/v1/task/XcqzYPXOTDm_bht2IccTyQ/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/c09bb537ffd30c56f54dfeca8c9d3882637ffb2936899d77fe1829355fa97c74580e4c5f0f64303701caf36a226e79e60b30b5fd437e3796115cc7d90c93fa9c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54cfb6ae3a9eb7ff5f9ac8525b0f3f3753d59473391fe0895ad4f0208aeaa4d18124248aa62d14f4c23c4a30102a9f6bb5b068b21ea7cc3e6afd972283626d89", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5521fc82e556ae7a718810ae903a3ea1109adb236913ff914be930dbab335cd758f6175a63120577bf6e1000984b0105c1bb33799460a3bb0a55c7fd8badd861", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/167df98b6415123b186772938fc791a42c143ebac776f67866a4c3cec36e0ad1d97bae09f8c53d216e8ec51b949ad034a1d9a53c0f4c5cb458b8388419d213d8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 05:39:46.860150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:39:46.860524) ========= 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.2xYvrUiCr0/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.yh9fviKmc0/Listeners TMPDIR=/var/folders/cf/5zhxcwh11mq5cjjb5nhm70lm00000w/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.005164 ========= master_lag: 0.67 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 05:39:47.532401) ========= ========= Started reboot skipped (results: 3, elapsed: 5 secs) (at 2017-04-06 05:39:47.532723) ========= ========= Finished reboot skipped (results: 3, elapsed: 5 secs) (at 2017-04-06 05:39:52.570815) ========= ========= Total master_lag: 6.18 =========