builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0285 starttime: 1493848562.27 results: success (0) buildid: 20170503131922 builduid: 12f75317b32345458c9c571ef90b15a8 revision: 96605941c0021795376d9c6ec1e458de2fac329e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.269513) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.269959) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.270237) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.298750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.299137) ========= 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.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-05-03 14:56:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-05-03 14:56:02 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.286229 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.611912) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.612319) ========= 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.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.020910 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:02.661520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 14:56:02.661896) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 96605941c0021795376d9c6ec1e458de2fac329e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 96605941c0021795376d9c6ec1e458de2fac329e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-05-03 14:56:02,746 truncating revision to first 12 chars 2017-05-03 14:56:02,746 Setting DEBUG logging. 2017-05-03 14:56:02,746 attempt 1/10 2017-05-03 14:56:02,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/96605941c002?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 14:56:03,934 unpacking tar archive at: mozilla-central-96605941c002/testing/mozharness/ program finished with exit code 0 elapsedTime=1.452528 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-03 14:56:04.140221) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:04.140687) ========= script_repo_revision: 96605941c0021795376d9c6ec1e458de2fac329e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:04.141115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:04.141387) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 14:56:04.162625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-03 14:56:04.163028) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:04 INFO - MultiFileLogger online at 20170503 14:56:04 in /builds/slave/test 14:56:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:56:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:56:04 INFO - {'append_to_log': False, 14:56:04 INFO - 'base_work_dir': '/builds/slave/test', 14:56:04 INFO - 'blob_upload_branch': 'Firefox', 14:56:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:56:04 INFO - 'branch': 'Firefox', 14:56:04 INFO - 'buildbot_json_path': 'buildprops.json', 14:56:04 INFO - 'config_files': ('talos/mac_config.py',), 14:56:04 INFO - 'default_actions': ('clobber', 14:56:04 INFO - 'read-buildbot-config', 14:56:04 INFO - 'download-and-extract', 14:56:04 INFO - 'populate-webroot', 14:56:04 INFO - 'create-virtualenv', 14:56:04 INFO - 'install', 14:56:04 INFO - 'run-tests'), 14:56:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:56:04 INFO - 'download_minidump_stackwalk': True, 14:56:04 INFO - 'download_symbols': 'ondemand', 14:56:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:56:04 INFO - 'tooltool.py': '/tools/tooltool.py', 14:56:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:56:04 INFO - '/tools/misc-python/virtualenv.py')}, 14:56:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:56:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:56:04 INFO - 'gecko_profile': False, 14:56:04 INFO - 'gecko_profile_interval': 0, 14:56:04 INFO - 'installer_path': 'installer.exe', 14:56:04 INFO - 'log_level': 'info', 14:56:04 INFO - 'log_name': 'talos', 14:56:04 INFO - 'log_to_console': True, 14:56:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:56:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:56:04 INFO - 'opt_config_files': (), 14:56:04 INFO - 'pip_index': False, 14:56:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:56:04 INFO - 'cmd': ('bash', 14:56:04 INFO - '-c', 14:56:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:56:04 INFO - 'enabled': True, 14:56:04 INFO - 'halt_on_failure': False, 14:56:04 INFO - 'name': 'check_screen_resolution'},), 14:56:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:56:04 INFO - 'cmd': ('bash', 14:56:04 INFO - '-c', 14:56:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:56:04 INFO - 'enabled': True, 14:56:04 INFO - 'halt_on_failure': False, 14:56:04 INFO - 'name': 'check_screen_resolution'},), 14:56:04 INFO - 'run_cmd_checks_enabled': True, 14:56:04 INFO - 'suite': 'dromaeojs', 14:56:04 INFO - 'system_bits': '32', 14:56:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:56:04 INFO - 'title': 't-yosemite-r7-0285', 14:56:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:56:04 INFO - 'use_talos_json': True, 14:56:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:56:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:56:04 INFO - 'work_dir': 'build'} 14:56:04 INFO - [mozharness: 2017-05-03 21:56:04.366790Z] Running clobber step. 14:56:04 INFO - Running pre-action listener: _resource_record_pre_action 14:56:04 INFO - Running main action method: clobber 14:56:04 INFO - rmtree: /builds/slave/test/build 14:56:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:56:06 INFO - Running post-action listener: _resource_record_post_action 14:56:06 INFO - [mozharness: 2017-05-03 21:56:06.082424Z] Finished clobber step (success) 14:56:06 INFO - [mozharness: 2017-05-03 21:56:06.082548Z] Running read-buildbot-config step. 14:56:06 INFO - Running pre-action listener: _resource_record_pre_action 14:56:06 INFO - Running main action method: read_buildbot_config 14:56:06 INFO - Using buildbot properties: 14:56:06 INFO - { 14:56:06 INFO - "project": "", 14:56:06 INFO - "product": "firefox", 14:56:06 INFO - "script_repo_revision": "production", 14:56:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:56:06 INFO - "repository": "", 14:56:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:56:06 INFO - "buildid": "20170503131922", 14:56:06 INFO - "pgo_build": "False", 14:56:06 INFO - "basedir": "/builds/slave/test", 14:56:06 INFO - "buildnumber": 33, 14:56:06 INFO - "slavename": "t-yosemite-r7-0285", 14:56:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:56:06 INFO - "platform": "yosemite_r7", 14:56:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:56:06 INFO - "branch": "mozilla-central", 14:56:06 INFO - "repo_path": "mozilla-central", 14:56:06 INFO - "revision": "96605941c0021795376d9c6ec1e458de2fac329e", 14:56:06 INFO - "stage_platform": "macosx64", 14:56:06 INFO - "builduid": "12f75317b32345458c9c571ef90b15a8", 14:56:06 INFO - "slavebuilddir": "test" 14:56:06 INFO - } 14:56:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:56:06 INFO - Running post-action listener: _resource_record_post_action 14:56:06 INFO - [mozharness: 2017-05-03 21:56:06.085933Z] Finished read-buildbot-config step (success) 14:56:06 INFO - [mozharness: 2017-05-03 21:56:06.086024Z] Running download-and-extract step. 14:56:06 INFO - Running pre-action listener: _resource_record_pre_action 14:56:06 INFO - Running main action method: download_and_extract 14:56:06 INFO - mkdir: /builds/slave/test/build/tests 14:56: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')]} 14:56:06 INFO - trying https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:56:06 INFO - Downloading https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:56:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:56:07 INFO - Downloaded 1325 bytes. 14:56:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:56:07 INFO - Using the following test package requirements: 14:56:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:56:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:56:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:56:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:56:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'jsshell-mac.zip'], 14:56:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:56:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:56:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:56:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:56:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:56:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:56:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:56:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:56:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:56:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:56:07 INFO - Fetch https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:56:08 INFO - Content-Length response header: 52703513 14:56:08 INFO - Bytes received: 52703513 14:56:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:56:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:56:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:56:25 INFO - Fetch https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:56:26 INFO - Content-Length response header: 52703513 14:56:26 INFO - Bytes received: 52703513 14:56:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:56:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:56:42 INFO - Fetch https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:56:44 INFO - Content-Length response header: 14408809 14:56:44 INFO - Bytes received: 14408809 14:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:44 INFO - trying https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:56:44 INFO - Downloading https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:56:46 INFO - Downloaded 70886822 bytes. 14:56:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:56:46 INFO - mkdir: /builds/slave/test/properties 14:56:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:56:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:56:46 INFO - Contents: 14:56:46 INFO - build_url:https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:56:47 INFO - Running post-action listener: _resource_record_post_action 14:56:47 INFO - Running post-action listener: set_extra_try_arguments 14:56:47 INFO - [mozharness: 2017-05-03 21:56:47.159031Z] Finished download-and-extract step (success) 14:56:47 INFO - [mozharness: 2017-05-03 21:56:47.159158Z] Running populate-webroot step. 14:56:47 INFO - Running pre-action listener: _resource_record_pre_action 14:56:47 INFO - Running main action method: populate_webroot 14:56:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:56:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:56:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:56:47 INFO - '--tppagecycles', 14:56:47 INFO - '7'], 14:56:47 INFO - 'tests': ['tsvgm']}}, 14:56:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:56:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:56:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:56:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:56:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:56:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:56:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['damp', 'tps']}, 14:56:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'tests': ['damp', 'tps']}, 14:56:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['dromaeo_dom']}, 14:56:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:56:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:56:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:56:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['a11yr', 14:56:47 INFO - 'ts_paint', 14:56:47 INFO - 'tpaint', 14:56:47 INFO - 'sessionrestore', 14:56:47 INFO - 'sessionrestore_no_auto_restore', 14:56:47 INFO - 'tabpaint']}, 14:56:47 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:56:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:56:47 INFO - 'bloom_basic_ref']}, 14:56:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['tsvgx', 14:56:47 INFO - 'tsvgr_opacity', 14:56:47 INFO - 'tart', 14:56:47 INFO - 'tscrollx', 14:56:47 INFO - 'cart', 14:56:47 INFO - 'tsvg_static']}, 14:56:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:56:47 INFO - 'tsvgr_opacity', 14:56:47 INFO - 'tart', 14:56:47 INFO - 'tscrollx', 14:56:47 INFO - 'cart', 14:56:47 INFO - 'tsvg_static']}, 14:56:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'talos_options': ['--disable-e10s'], 14:56:47 INFO - 'tests': ['tp5o']}, 14:56:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:56:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'talos_options': ['--disable-e10s', 14:56:47 INFO - '--xperf_path', 14:56:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:56:47 INFO - 'tests': ['tp5n']}, 14:56:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:56:47 INFO - 'talos_options': ['--xperf_path', 14:56:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:56:47 INFO - 'tests': ['tp5n']}}, 14:56:47 INFO - 'talos.zip': {'path': '', 14:56:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:56:47 INFO - Running post-action listener: _resource_record_post_action 14:56:47 INFO - [mozharness: 2017-05-03 21:56:47.178038Z] Finished populate-webroot step (success) 14:56:47 INFO - [mozharness: 2017-05-03 21:56:47.178162Z] Running create-virtualenv step. 14:56:47 INFO - Running pre-action listener: _resource_record_pre_action 14:56:47 INFO - Running main action method: create_virtualenv 14:56:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:56:47 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 14:56:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:56:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:56:47 INFO - Using real prefix '/tools/python27' 14:56:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:56:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:56:50 INFO - Installing pip.................done. 14:56:50 INFO - Return code: 0 14:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:56:51 INFO - Reading from file tmpfile_stdout 14:56:51 INFO - Output received: 14:56:51 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) 14:56:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:56:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:56:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:56:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:56:51 INFO - 'HOME': '/Users/cltbld', 14:56:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:56:51 INFO - 'LOGNAME': 'cltbld', 14:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:51 INFO - 'MOZ_NO_REMOTE': '1', 14:56:51 INFO - 'NO_EM_RESTART': '1', 14:56:51 INFO - 'PAGER': '/bin/cat', 14:56:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:51 INFO - 'PWD': '/builds/slave/test', 14:56:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:56:51 INFO - 'SHELL': '/bin/bash', 14:56:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:56:51 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:56:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:56:51 INFO - 'USER': 'cltbld', 14:56:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:56:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:51 INFO - 'XPC_FLAGS': '0x0', 14:56:51 INFO - 'XPC_SERVICE_NAME': '0', 14:56:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:51 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 14:56:51 INFO - Cleaning up... 14:56:51 INFO - Return code: 0 14:56:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:56:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:56:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:56:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:56:51 INFO - 'HOME': '/Users/cltbld', 14:56:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:56:51 INFO - 'LOGNAME': 'cltbld', 14:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:51 INFO - 'MOZ_NO_REMOTE': '1', 14:56:51 INFO - 'NO_EM_RESTART': '1', 14:56:51 INFO - 'PAGER': '/bin/cat', 14:56:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:51 INFO - 'PWD': '/builds/slave/test', 14:56:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:56:51 INFO - 'SHELL': '/bin/bash', 14:56:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:56:51 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:56:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:56:51 INFO - 'USER': 'cltbld', 14:56:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:56:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:51 INFO - 'XPC_FLAGS': '0x0', 14:56:51 INFO - 'XPC_SERVICE_NAME': '0', 14:56:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:51 INFO - Downloading/unpacking psutil>=3.1.1 14:56:51 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 14:56:51 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 14:56:51 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 14:56:51 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 14:56:51 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 14:56:51 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 14:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:56:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:56:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:56:54 INFO - Installing collected packages: psutil 14:56:54 INFO - Running setup.py install for psutil 14:56:54 INFO - building 'psutil._psutil_osx' extension 14:56:54 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 14:56:54 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 14:56:54 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 14:56:54 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 14:56:54 INFO - building 'psutil._psutil_posix' extension 14:56:54 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 14:56:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:56:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:56:54 INFO - ^ 14:56:54 INFO - 1 warning generated. 14:56:54 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 14:56:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:56:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:56:54 INFO - Successfully installed psutil 14:56:54 INFO - Cleaning up... 14:56:54 INFO - Return code: 0 14:56:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:54 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:54 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 14:56:54 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 14:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:56:54 INFO - 'HOME': '/Users/cltbld', 14:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:56:54 INFO - 'LOGNAME': 'cltbld', 14:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:54 INFO - 'MOZ_NO_REMOTE': '1', 14:56:54 INFO - 'NO_EM_RESTART': '1', 14:56:54 INFO - 'PAGER': '/bin/cat', 14:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:54 INFO - 'PWD': '/builds/slave/test', 14:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:56:54 INFO - 'SHELL': '/bin/bash', 14:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:56:54 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:56:54 INFO - 'USER': 'cltbld', 14:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:54 INFO - 'XPC_FLAGS': '0x0', 14:56:54 INFO - 'XPC_SERVICE_NAME': '0', 14:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:56:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:56:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:56:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:56:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:56:56 INFO - Installing collected packages: mozsystemmonitor 14:56:56 INFO - Running setup.py install for mozsystemmonitor 14:56:56 INFO - Successfully installed mozsystemmonitor 14:56:56 INFO - Cleaning up... 14:56:56 INFO - Return code: 0 14:56:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:56 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:56 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 14:56:56 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 14:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:56:56 INFO - 'HOME': '/Users/cltbld', 14:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:56:56 INFO - 'LOGNAME': 'cltbld', 14:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:56 INFO - 'MOZ_NO_REMOTE': '1', 14:56:56 INFO - 'NO_EM_RESTART': '1', 14:56:56 INFO - 'PAGER': '/bin/cat', 14:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:56 INFO - 'PWD': '/builds/slave/test', 14:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:56:56 INFO - 'SHELL': '/bin/bash', 14:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:56:56 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:56:56 INFO - 'USER': 'cltbld', 14:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:56 INFO - 'XPC_FLAGS': '0x0', 14:56:56 INFO - 'XPC_SERVICE_NAME': '0', 14:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:57 INFO - Downloading/unpacking jsonschema==2.5.1 14:56:57 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 14:56:57 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 14:56:57 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 14:56:57 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 14:56:57 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 14:56:57 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 14:56:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:56:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:56:58 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 14:56:58 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 14:56:58 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 14:56:58 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 14:56:58 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 14:56:58 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 14:56:58 INFO - Downloading functools32-3.2.3-2.tar.gz 14:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:56:58 INFO - warning: no files found matching '*.txt' 14:56:58 INFO - no previously-included directories found matching 'build' 14:56:58 INFO - no previously-included directories found matching 'dist' 14:56:58 INFO - no previously-included directories found matching '.git*' 14:56:58 INFO - Installing collected packages: jsonschema, functools32 14:56:58 INFO - Running setup.py install for functools32 14:56:58 INFO - warning: no files found matching '*.txt' 14:56:58 INFO - no previously-included directories found matching 'build' 14:56:58 INFO - no previously-included directories found matching 'dist' 14:56:58 INFO - no previously-included directories found matching '.git*' 14:56:58 INFO - Successfully installed jsonschema functools32 14:56:58 INFO - Cleaning up... 14:56:58 INFO - Return code: 0 14:56:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:56:58 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')]} 14:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:56:59 INFO - 'HOME': '/Users/cltbld', 14:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:56:59 INFO - 'LOGNAME': 'cltbld', 14:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:56:59 INFO - 'MOZ_NO_REMOTE': '1', 14:56:59 INFO - 'NO_EM_RESTART': '1', 14:56:59 INFO - 'PAGER': '/bin/cat', 14:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:56:59 INFO - 'PWD': '/builds/slave/test', 14:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:56:59 INFO - 'SHELL': '/bin/bash', 14:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:56:59 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:56:59 INFO - 'USER': 'cltbld', 14:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:56:59 INFO - 'XPC_FLAGS': '0x0', 14:56:59 INFO - 'XPC_SERVICE_NAME': '0', 14:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:56:59 INFO - Cleaning up... 14:56:59 INFO - Return code: 0 14:56:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:56: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')]} 14:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:56: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')]} 14:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:00 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:57:00 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 14:57:00 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 14:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:00 INFO - 'HOME': '/Users/cltbld', 14:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:00 INFO - 'LOGNAME': 'cltbld', 14:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:00 INFO - 'MOZ_NO_REMOTE': '1', 14:57:00 INFO - 'NO_EM_RESTART': '1', 14:57:00 INFO - 'PAGER': '/bin/cat', 14:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:00 INFO - 'PWD': '/builds/slave/test', 14:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:00 INFO - 'SHELL': '/bin/bash', 14:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:00 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:00 INFO - 'USER': 'cltbld', 14:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:00 INFO - 'XPC_FLAGS': '0x0', 14:57:00 INFO - 'XPC_SERVICE_NAME': '0', 14:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:00 INFO - Downloading/unpacking blobuploader==1.2.4 14:57:00 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 14:57:00 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 14:57:00 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 14:57:00 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 14:57:00 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 14:57:00 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 14:57:02 INFO - Downloading blobuploader-1.2.4.tar.gz 14:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:57:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:57:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:02 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 14:57:03 INFO - Downloading docopt-0.6.1.tar.gz 14:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:57:03 INFO - Installing collected packages: blobuploader, requests, docopt 14:57:03 INFO - Running setup.py install for blobuploader 14:57:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:57:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:57:03 INFO - Running setup.py install for requests 14:57:03 INFO - Running setup.py install for docopt 14:57:04 INFO - Successfully installed blobuploader requests docopt 14:57:04 INFO - Cleaning up... 14:57:04 INFO - Return code: 0 14:57:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:57:04 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')]} 14:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:04 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')]} 14:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:04 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:57:04 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 14:57:04 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 14:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:04 INFO - 'HOME': '/Users/cltbld', 14:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:04 INFO - 'LOGNAME': 'cltbld', 14:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:04 INFO - 'MOZ_NO_REMOTE': '1', 14:57:04 INFO - 'NO_EM_RESTART': '1', 14:57:04 INFO - 'PAGER': '/bin/cat', 14:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:04 INFO - 'PWD': '/builds/slave/test', 14:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:04 INFO - 'SHELL': '/bin/bash', 14:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:04 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:04 INFO - 'USER': 'cltbld', 14:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:04 INFO - 'XPC_FLAGS': '0x0', 14:57:04 INFO - 'XPC_SERVICE_NAME': '0', 14:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-DqptKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-UE9vMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-A9sIQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-A1pJzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-LIkBWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:57:04 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-FR3UcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-ee755t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-TFqWM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_NTRxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-wPtvUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-ijlmp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-xASeT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-sWWVjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-L2ODyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-vmEKOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:57:05 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-IAKeuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:57:06 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-0P4dUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:57:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:57:06 INFO - Running setup.py install for manifestparser 14:57:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:57:06 INFO - Running setup.py install for mozcrash 14:57:06 INFO - Running setup.py install for mozdebug 14:57:06 INFO - Running setup.py install for mozdevice 14:57:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:57:06 INFO - Running setup.py install for mozfile 14:57:06 INFO - Running setup.py install for mozhttpd 14:57:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Running setup.py install for mozinfo 14:57:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Running setup.py install for mozInstall 14:57:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Running setup.py install for mozleak 14:57:07 INFO - Running setup.py install for mozlog 14:57:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Running setup.py install for moznetwork 14:57:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:57:07 INFO - Running setup.py install for mozprocess 14:57:07 INFO - Running setup.py install for mozprofile 14:57:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:57:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:57:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:57:08 INFO - Running setup.py install for mozrunner 14:57:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:57:08 INFO - Running setup.py install for mozscreenshot 14:57:08 INFO - Running setup.py install for moztest 14:57:08 INFO - Running setup.py install for mozversion 14:57:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:57:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:57:08 INFO - Cleaning up... 14:57:08 INFO - Return code: 0 14:57:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:57:08 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')]} 14:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:08 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')]} 14:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:08 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:57:08 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 14:57:08 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 14:57:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:08 INFO - 'HOME': '/Users/cltbld', 14:57:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:08 INFO - 'LOGNAME': 'cltbld', 14:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:08 INFO - 'MOZ_NO_REMOTE': '1', 14:57:08 INFO - 'NO_EM_RESTART': '1', 14:57:08 INFO - 'PAGER': '/bin/cat', 14:57:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:08 INFO - 'PWD': '/builds/slave/test', 14:57:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:08 INFO - 'SHELL': '/bin/bash', 14:57:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:08 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:08 INFO - 'USER': 'cltbld', 14:57:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:08 INFO - 'XPC_FLAGS': '0x0', 14:57:08 INFO - 'XPC_SERVICE_NAME': '0', 14:57:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-AuZbNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-fCQPQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-9Mktzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-D19n37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-medS3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-6HhiSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-leJmZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-M4rcvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:57:09 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Gnaiah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:57:09 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)) 14:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-n9mQys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Yiq5oP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-TrJrl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-3s8mZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-NKwbvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-pUB1tS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-SQBvki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:57:10 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)) 14:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:57:10 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Z951d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:57:10 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)) 14:57:10 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)) 14:57:10 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)) 14:57:10 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)) 14:57:10 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:57:10 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:57:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:57:10 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 14:57:10 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 14:57:10 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 14:57:10 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 14:57:10 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 14:57:10 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 14:57:12 INFO - Downloading blessings-1.6.tar.gz 14:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:57:12 INFO - Installing collected packages: blessings 14:57:12 INFO - Running setup.py install for blessings 14:57:12 INFO - Successfully installed blessings 14:57:12 INFO - Cleaning up... 14:57:12 INFO - Return code: 0 14:57:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:57:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:57:12 INFO - Reading from file tmpfile_stdout 14:57:12 INFO - Current package versions: 14:57:12 INFO - blessings == 1.6 14:57:12 INFO - blobuploader == 1.2.4 14:57:12 INFO - docopt == 0.6.1 14:57:12 INFO - functools32 == 3.2.3-2 14:57:12 INFO - jsonschema == 2.5.1 14:57:12 INFO - manifestparser == 1.1 14:57:12 INFO - mozInstall == 1.12 14:57:12 INFO - mozcrash == 1.0 14:57:12 INFO - mozdebug == 0.1 14:57:12 INFO - mozdevice == 0.50 14:57:12 INFO - mozfile == 1.2 14:57:12 INFO - mozhttpd == 0.7 14:57:12 INFO - mozinfo == 0.9 14:57:12 INFO - mozleak == 0.1 14:57:12 INFO - mozlog == 3.4 14:57:12 INFO - moznetwork == 0.27 14:57:12 INFO - mozprocess == 0.25 14:57:12 INFO - mozprofile == 0.28 14:57:12 INFO - mozrunner == 6.13 14:57:12 INFO - mozscreenshot == 0.1 14:57:12 INFO - mozsystemmonitor == 0.3 14:57:12 INFO - moztest == 0.8 14:57:12 INFO - mozversion == 1.4 14:57:12 INFO - psutil == 3.1.1 14:57:12 INFO - requests == 1.2.3 14:57:12 INFO - wsgiref == 0.1.2 14:57:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:57: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')]} 14:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57: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')]} 14:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:12 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 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:57:12 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 14:57:12 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 14:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:12 INFO - 'HOME': '/Users/cltbld', 14:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:12 INFO - 'LOGNAME': 'cltbld', 14:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:12 INFO - 'MOZ_NO_REMOTE': '1', 14:57:12 INFO - 'NO_EM_RESTART': '1', 14:57:12 INFO - 'PAGER': '/bin/cat', 14:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:12 INFO - 'PWD': '/builds/slave/test', 14:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:12 INFO - 'SHELL': '/bin/bash', 14:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:12 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:12 INFO - 'USER': 'cltbld', 14:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:12 INFO - 'XPC_FLAGS': '0x0', 14:57:12 INFO - 'XPC_SERVICE_NAME': '0', 14:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 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)) 14:57:13 INFO - Cleaning up... 14:57:13 INFO - Return code: 0 14:57:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:57: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103164b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103167768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2395b8a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, '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.Vk2G3TAALT/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.VGujt5R1Za/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 14:57: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', 'jsonschema'] in /builds/slave/test/build 14:57: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 jsonschema 14:57:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:13 INFO - 'HOME': '/Users/cltbld', 14:57:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:13 INFO - 'LOGNAME': 'cltbld', 14:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:13 INFO - 'MOZ_NO_REMOTE': '1', 14:57:13 INFO - 'NO_EM_RESTART': '1', 14:57:13 INFO - 'PAGER': '/bin/cat', 14:57:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:13 INFO - 'PWD': '/builds/slave/test', 14:57:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:13 INFO - 'SHELL': '/bin/bash', 14:57:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:13 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:13 INFO - 'USER': 'cltbld', 14:57:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:13 INFO - 'XPC_FLAGS': '0x0', 14:57:13 INFO - 'XPC_SERVICE_NAME': '0', 14:57:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:57:13 INFO - Cleaning up... 14:57:13 INFO - Return code: 0 14:57:13 INFO - Running post-action listener: _resource_record_post_action 14:57:13 INFO - Running post-action listener: _start_resource_monitoring 14:57:13 INFO - Starting resource monitoring. 14:57:13 INFO - [mozharness: 2017-05-03 21:57:13.440354Z] Finished create-virtualenv step (success) 14:57:13 INFO - [mozharness: 2017-05-03 21:57:13.441173Z] Running install step. 14:57:13 INFO - Running pre-action listener: _resource_record_pre_action 14:57:13 INFO - Running main action method: install 14:57:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:57:13 INFO - Reading from file tmpfile_stdout 14:57:13 INFO - Detecting whether we're running mozinstall >=1.0... 14:57:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:57:13 INFO - Reading from file tmpfile_stdout 14:57:13 INFO - Output received: 14:57:13 INFO - Usage: mozinstall [options] installer 14:57:13 INFO - Options: 14:57:13 INFO - -h, --help show this help message and exit 14:57:13 INFO - -d DEST, --destination=DEST 14:57:13 INFO - Directory to install application into. [default: 14:57:13 INFO - "/builds/slave/test"] 14:57:13 INFO - --app=APP Application being installed. [default: firefox] 14:57:13 INFO - mkdir: /builds/slave/test/build/application 14:57:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:57:33 INFO - Reading from file tmpfile_stdout 14:57:33 INFO - Output received: 14:57:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:57:33 INFO - Running post-action listener: _resource_record_post_action 14:57:33 INFO - [mozharness: 2017-05-03 21:57:33.571428Z] Finished install step (success) 14:57:33 INFO - [mozharness: 2017-05-03 21:57:33.571575Z] Running run-tests step. 14:57:33 INFO - Running pre-action listener: _resource_record_pre_action 14:57:33 INFO - Running main action method: run_tests 14:57:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:57:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:57:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:57:33 INFO - 2017-05-03 14:57:33.585 screenresolution[2510:34793] starting screenresolution argv=screenresolution get 14:57:33 INFO - 2017-05-03 14:57:33.588 screenresolution[2510:34793] Display 0: 1600x1200x32@60 14:57:33 INFO - 2017-05-03 14:57:33.594 screenresolution[2511:34796] starting screenresolution argv=screenresolution list 14:57:33 INFO - Available Modes on Display 0 14:57:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:57:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:57:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:57:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:57:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:57:33 INFO - Intel Iris: 14:57:33 INFO - Chipset Model: Intel Iris 14:57:33 INFO - Type: GPU 14:57:33 INFO - Bus: Built-In 14:57:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:57:33 INFO - Vendor: Intel (0x8086) 14:57:33 INFO - Device ID: 0x0a2e 14:57:33 INFO - Revision ID: 0x0009 14:57:33 INFO - Displays: 14:57:33 INFO - Display: 14:57:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:57:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:57:33 INFO - Display Serial Number: 1600x1200 60 14:57:33 INFO - Main Display: Yes 14:57:33 INFO - Mirror: Off 14:57:33 INFO - Online: Yes 14:57:33 INFO - Rotation: Supported 14:57:33 INFO - Return code: 0 14:57:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:57:33 INFO - Python 2.7.3 14:57:33 INFO - Return code: 0 14:57:33 INFO - grabbing minidump binary from tooltool 14:57:33 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')]} 14:57:33 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 0x102f42d30>, '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 0x102e68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f37be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:57:33 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 14:57:33 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 14:57:33 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 14:57:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:57:33 INFO - Return code: 0 14:57:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:57:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:57:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:57:33 INFO - ENV: RUST_BACKTRACE is now 1 14:57:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:57:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:57:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:57:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0285 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vk2G3TAALT/Render', 14:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:57:33 INFO - 'HOME': '/Users/cltbld', 14:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:57:33 INFO - 'LOGNAME': 'cltbld', 14:57:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:57:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:57:33 INFO - 'MOZ_NO_REMOTE': '1', 14:57:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:57:33 INFO - 'NO_EM_RESTART': '1', 14:57:33 INFO - 'PAGER': '/bin/cat', 14:57:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:57:33 INFO - 'PWD': '/builds/slave/test', 14:57:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:57:33 INFO - 'RUST_BACKTRACE': '1', 14:57:33 INFO - 'SHELL': '/bin/bash', 14:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VGujt5R1Za/Listeners', 14:57:33 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 14:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:57:33 INFO - 'USER': 'cltbld', 14:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:57:33 INFO - 'XPC_FLAGS': '0x0', 14:57:33 INFO - 'XPC_SERVICE_NAME': '0', 14:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:57:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:57:34 INFO - mozversion application_buildid: 20170503131922 14:57:34 INFO - mozversion application_changeset: 96605941c0021795376d9c6ec1e458de2fac329e 14:57:34 INFO - mozversion application_display_name: Nightly 14:57:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:57:34 INFO - mozversion application_name: Firefox 14:57:34 INFO - mozversion application_remotingname: firefox 14:57:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:57:34 INFO - mozversion application_vendor: Mozilla 14:57:34 INFO - mozversion application_version: 55.0a1 14:57:34 INFO - mozversion platform_buildid: 20170503131922 14:57:34 INFO - mozversion platform_changeset: 96605941c0021795376d9c6ec1e458de2fac329e 14:57:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:57:34 INFO - mozversion platform_version: 55.0a1 14:57:34 INFO - using testdate: 1493848654 14:57:34 INFO - actual date: 1493848654 14:57:34 INFO - starting webserver on 'localhost:60097' 14:57:34 INFO - SUITE-START | Running 2 tests 14:57:34 INFO - TEST-START | dromaeo_css 14:57:34 INFO - operating with platform_type : mac_ 14:57:34 INFO - Initialising browser for dromaeo_css test... 14:57:34 INFO - TEST-INFO | started process 2521 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpxBW7TI/profile http://localhost:60097/getInfo.html) 14:57:36 INFO - PID 2521 | __metrics Screen width/height:1600/1200 14:57:36 INFO - PID 2521 | colorDepth:24 14:57:36 INFO - PID 2521 | Browser inner width/height: 1024/691 14:57:36 INFO - PID 2521 | __metrics 14:57:36 INFO - PID 2521 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:57:36 INFO - PID 2521 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:57:37 INFO - TEST-INFO | 2521: exit 0 14:57:37 INFO - Browser initialized. 14:57:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:57:37 INFO - TEST-INFO | started process 2523 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpxBW7TI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:57:50 INFO - PID 2523 | RSS: Main: 240513024 14:57:50 INFO - PID 2523 | 15:00:55 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60097/tests/dromaeo/cssquery-ext.html) 15:00:56 INFO - PID 2523 | RSS: Main: 369119232 15:00:56 INFO - PID 2523 | 15:03:59 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-ext.html (next: http://localhost:60097/tests/dromaeo/cssquery-jquery.html) 15:04:00 INFO - PID 2523 | RSS: Main: 365445120 15:04:00 INFO - PID 2523 | 15:07:02 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60097/tests/dromaeo/cssquery-mootools.html) 15:07:02 INFO - PID 2523 | RSS: Main: 333860864 15:07:02 INFO - PID 2523 | 15:10:05 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60097/tests/dromaeo/cssquery-prototype.html) 15:10:05 INFO - PID 2523 | RSS: Main: 328388608 15:10:05 INFO - PID 2523 | 15:13:09 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60097/tests/dromaeo/cssquery-yui.html) 15:13:10 INFO - PID 2523 | RSS: Main: 329662464 15:13:10 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | Cycle 1(1): loaded http://localhost:60097/tests/dromaeo/cssquery-yui.html (next: http://localhost:60097/tests/dromaeo/cssquery-dojo.html) 15:16:12 INFO - PID 2523 | RSS: Main: 373239808 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | __start_tp_report 15:16:12 INFO - PID 2523 | _x_x_mozilla_page_load 15:16:12 INFO - PID 2523 | _x_x_mozilla_page_load_details 15:16:12 INFO - PID 2523 | |i|pagename|runs| 15:16:12 INFO - PID 2523 | |0;dojo.html;3497.9825100874477;3514.5079688843553;3517.6834084932357;3539.893803185905;3574.0886074051114;5016.4231113421965;6125.846853828645;6169.722362493687;6182.907256391152;6215.347388524207;6108.06889217957;6110.892920173598;6182.443580077792;6194.504439644829;6204;5187.481251874801;5231.346081739783;5306.257124002624;5330.440303768107;5364.490373414539;5419.403865574784;5423.267858839065;5425.782968681248;5435.755391007414;5455.9633669602645;5207.505286997729;5302.628815982883;5324.41431442541;5373.516383525502;5405.135178371462;5322.7072511011875;5386.649867758583;5388.568914486832;5405.891882162374;5426.701531415771;5290.523852853262;5332.866678333073;5335.706536140511;5348.893022139536;5357.544608708265;6048.7882924097885;6158.6612736299485;6175.59858609189;6180.752769889199;6187.752489900398;6324.873502529969;6353.809385718436;6401.8399540011405;6490.675466226717;6515.381038801306;5683.54531637468;6601.108850305243;6626.97281921303;6628.900566491507;6637.070810086591;6209.5032397408095;6210.937890621126;6238.189035425411;6273.811785646347;6273.905891411632;6493.805185844432;6592.868142637216;6659.467242620579;6756.831079223059;6785.491088168308;6355;6360.109584658151;6364.331745166784;6367.649779262185;6374.330695277022;6456.192975878015;6588.901166482507;6591.505637077238;6594.406503414716;6618.205815302194;5503.834884953458;6249.75000999964;6322.241331040304;6454.580452270588;6487.870242595121;5502.642328248651;6435.967820160869;6478.028295755675;6490.967545162339;6505.544611877218;5251.842444726588;5261.394939581921;5263.605229607795;5271.894562108736;5298;4613.930791038137;4710.3405523226775;4720.976395118002;4731.384919960383;4746.976265118652;6172.722227499773;6175.567710260329;6178.62928224308;6234.875302494015;6274.247090349096;6569.310222426672;6572.244191917991;6611.834704132339;6616.636085015274;6622.701978410983;6256.499480041586;6265.373462653698;6278.748850045947;6284;6284.654344011124;5598.10430331145;5601.495865372136;5736.569757268222;5748.051571490657;5825.533957283407;6645;6651.700673469609;6655.73377064912;6658.43403310724;6665.333466653296;6426.807195784134;6434.292227855026;6436.066770318372;6444.45222156122;6473.255575608865;6808.7276508939085;6813.8977915330315;6817.147856517935;6830.31696830313;6832.248452670302;6465.709043092978;6479.611223326616;6497.707603157774;6498.350164983463;6501.642409667514;6371.585846919936;6463.838404039937;6472.061551074974;6604.900926486011;6607.339266073546;6369.108324834619;6369.649669268235;6372.617642941438;6378.87242255143;6392.105105285171;6369.936300636934;6383.489320854228;6384.776532821329;6386.648734319657;6395.872082558416;5307.920381194363;6167.475764560063;6226.688665566794;6268.341824108403;6275.121482992294;6337.271213810471;6368.299487056327;6381.680915954276;6382.585131966407;6383.361663833765;6228.844278893064;6245.063240513778;6247.375262473716;6249.218847644044;6250.874982500415 15:16:12 INFO - PID 2523 | |1;ext.html;778.2178910195253;1076.6554702495202;1091.3506463654128;1101.053094338869;1214.8602910665277;33976.66023339769;35586.466203006945;35611.82194089026;35710;35772.64227357729;35716.464253036225;36020.819895900546;36057.81971090147;36069.81965090177;36081.81959090201;5838.2702162229725;5931.584789064767;5945.672987985659;7453.739119130818;7595.620218989028;5835.854103647422;5904.55715821313;7907.802304942394;8062.879056814152;8066.03207615087;7086.503944723871;8084.7574572762915;8114.350851931861;8129;8146.470479418848;8054.23484768946;8097.2712455879255;8098.554579498125;8130.227628375294;8135.942327497417;5799.739011744482;5899.469047785677;7816.452848300622;7955.801104972365;7967.601619918981;35426.405811738536;35576;35637.46543801819;35729.28541429183;35804.56781728728;3573.3032058748554;3620.9094772630633;3621.6378362163837;3628.6552777486095;3628.909277268089;29110.27224319388;29151.562726559117;29163.562546561818;29204.269893252625;29263.97576084848;3072.2319420144963;3129.5618613394145;3130;3151.6848315168527;3160.952585711216;291964;298247;298636;298896;299468.5026574875;37362.62637373646;37700.245995079946;43528.91177720519;44796.880077998314;47033.76483117631;41876.371854421595;44449;47003.529964700596;47116.528834711215;47185.29222061672;13461.192328460324;13574.864251357558;13578.388972496065;13593.660158496117;13595.932020340033;11034;11039.448027598588;11047.83428248577;11061.668149955514;11136.777264454828;11903.464344104535;11949.462274197682;11960.282383057045;11976.820347694791;12114.454849531792;4372.934405983913;5041.949580504148;5062.949370506248;5097.515736005073;5107.948920510822;998.9550470228712;1000.7648202672366;1007.9193664506822;1021.933574317667;1029.8815636201932;20917.37247882566;22758.38313040087;23528.764712353;23550.64674029891;23572;1092.0553721031251;1097.3032124600777;1133.4332833583208;1137.4199158429096;1147.5008371358485;11748.709001744748;11971.820422693667;12040.578579749666;12051.939740301243;12068.0345572354;265064.58966565254;310864;311322;311485;311789;50115.24827127596;50116.49883501118;50246;50249.74875125601;50285.49714502808;1426.1514398932825;1462.1884853905972;1506.6007508010387;1510.1543135843963;1534.3018926388465;23137.652935206323;23158.88420557954;23232.419189519722;23246.418839529146;23343.299701008997;36499.27001460009;36515.452268215464;36573.81713091524;36598.634013659524;36658.63341366552;271071;271176;271942;272602;272927.2707272902;28309.319788427372;28538.286542836595;31491.37017259688;32360.514592281605;32562;33428.665713342554;40626.79686601548;41451.7927410361;41497;41682.58317416787;26633.600495992963;27217.59173612437;29226;29245.582688107326;29758.85787411049;28762.424751504434;29644.703552964194;29665.555016674334;30017.699823002364;30095.24761880883;34385.31229375448;35128.29743405169;35614.287714246086;35999.100022499646;36249;28700;29407.411851762416;30189.396212076073;30251.394972099995;30407.69592304137;20974.895125524275;22215.777842222018;22543;23542.411439714146;23603.173888913807 15:16:12 INFO - PID 2523 | |2;jquery.html;419.12233672691343;434.15123433687137;434.51768536923987;437.56025194679347;450.407713856279;19064.428067157973;20495.795042049576;20940.37178884632;21132.89433552832;21155.894220528877;21180.682289765668;21516.56966860666;21833.344999649995;21841.344759657197;22544.985475653593;5371.57027437806;5941.494972927296;6209.689515524228;6228.813135605917;6238.844028899269;5928.88142237155;6090.451859332659;6170.167027451282;6192.752289908398;6220.377962203788;5398.595105367113;6020;6186.288576813657;6201.565890387675;6202.3797620238065;6062;6205.379462053804;6213.887278325368;6218.813435596962;6222.004479283338;5193.428722840522;5346.652467589594;5533.170024496318;5535.114381698909;5540.695261760601;27294.863525682442;27604.861975690194;27800.30499237515;28306;28353.574696379572;2181.596404665126;2260.3670972127666;2261.3894248552965;2262.9547409051893;2276.226083131743;17247.31010759568;17253.82746172535;17540.473785786446;17671.293148274053;17849;2092.7384076990375;2095.1200495791804;2114.1437717724193;2121.8408619353454;2123.256860098953;142558.28720856432;158777;160457;162166.1891690534;164106;11404.600838970762;11959.641210763692;11983.041356691443;12006.339651319087;12055.336956467478;16093;16105.516834494983;16157.919210403872;16158.838411615969;16193.190340483165;8592.44149130317;8824;8827.911720882837;8931.642734290555;9012.27901767857;6970.477214208955;8350.958245208734;8527.062023177445;8569.528675922884;8570.871436928452;6167.907481387782;7557.6221188940335;7588.658510367047;7604.5437273762645;7620.771376858703;1806.9638607227782;1863.9347622833136;2058.8970551472366;2114.598226336991;2247.9550408992054;941.2987324443334;953.9475328856979;958.9760255993516;981.2199301555281;988.3625061835077;17406.39077632277;18211.635767284846;20055.899720501595;20643.260250711803;21443.035063422183;977.0913050862766;997.9172597731398;1004.7036124343336;1017.6692574913183;1020.550957578663;10144.28989970695;10582.947085264525;10622.73443163927;10669.253152279422;10690.786184276427;537150;541470;544825;559888;560499;120710.58578828374;120902;121269.78730212762;123480;124110;1146.908247340211;1176.2413243457922;1249.2067537113817;1260.4958016793173;1267.4549943524373;17803.643927121127;21894.67157992663;22122.668159977937;22127.89360531999;22141.557168856856;43570.910727232076;44744.32883506685;54421;55225.72387138039;57392;75862.86205707029;76656;76991.61504192444;77677;78735.21264787507;8695.826083478421;8765.123487651184;8881.644734210819;9047.773805654648;9126.361154719107;13838.930805346312;13987.020908536304;13999.300034998412;14378.065425747292;14388.424463021362;11785.587504437304;11786.174967752175;11989.460474278503;12073.939630301793;12221.38893055326;11153.72115697114;11181.72045698864;11309.604163854226;11499.770004600028;11559.422028898689;11860.406979651156;12088.516459341528;12359.938200308941;12399;12482.875171248172;8150.4294699370475;8589.355798315026;8919.375643705138;9049.366544341832;9079.909200907907;11228.887711122785;11311.773764524827;11556.94221528887;11621.360825154698;11666.883331166919 15:16:12 INFO - PID 2523 | |3;mootools.html;1199.2145144930064;1333.293354522103;1422.608782584789;1435.8492358302371;1465.9633509162272;9140.26877849772;9466.76333091673;11378.544858205662;11595.652130436101;11621.47703353349;11222.214444988855;11335.319880807157;11336.48985795639;11341.37622430766;11370.976612104909;2464.519418713356;2514.270861450177;2514.283429222672;2516.534441128388;2517.332906779705;2114.2071723103836;2179.259051922346;2381.523695260946;2534.531111744333;2543.224316583441;2450.938726531842;2471.295680730992;2474.5298393305306;2476.4428003699204;2476.8761561921833;2152.4726442021647;2449.9020039198413;2498.7001559812757;2499.687539057618;2502.461970676295;2442.792362649177;2474.3319303787875;2487.1916627096266;2492.750724927511;2492.950140997188;11190.66428007161;11436.542538298458;11459.713507162303;11466.197366184371;11493.195476316661;6407.13503677007;6647.833804154887;6657.733690652368;6669.666516674147;6683.732650693966;3904.2777086239134;4070.6743460523385;4140.4617399737845;4161.396597493377;4212.85255016073;1101.7080473674482;1229.0781913564826;1273.3378643105625;1276.9233845969272;1284.665986843418;16326.836731632651;16329.91835040829;16357.83642163587;16364.181790910288;16387.344506219735;5517.944820551824;5790.89790897909;5792.4497172768515;5908.970455147697;5942.61373010753;13534.255615941021;13552.357290328291;13574.321283935764;13577.456901724017;13588.93205533966;6625.370589793962;6637.435817955431;6637.867242655217;6647;6652.467802575782;5859.736311865976;6042.939570604326;6104.053871649901;6121.571489995657;6122.387761223931;3279.6720327967014;3929.685625149981;3936.9212615747524;3939.5075615548058;3945.8421663133154;1233.3771445420005;1243.7512497500536;1244.7012716948047;1244.8132780082879;1250.843644544432;796.0487217774704;799.0531220503722;817.1052697296451;823.7446391618618;847.5719761520393;6417.871642567123;6766.221884483348;6975.093237879043;6983.8603227935155;6994.090768200102;928.178561972659;932.9720108396759;940.0505489455699;940.4357385568741;944.4474982135528;4070.816813243411;4223.556526564728;4375.16871794358;4411.867643970686;4516.3902873112365;14524.20116893581;14829.77755333671;15088.17015064182;15108;15115.395384184729;14291.213983231022;14641.121532708072;14891.47879824201;14972.326245318987;14984.925075374553;7396.815079623052;8457.746267611981;8566.742997710078;8613.267872230805;8706.347023973101;6944.131983502062;6987.371136597731;7121.572705637678;7150.177729561167;7154.069970903957;12820;13065.542706005244;13178.670533236746;13607.86392136066;13617.79573306421;13417.463301467831;13719;13831.654208644864;13878.444862205399;14238.715225695636;9268.814623707623;9293.395929264576;9398.389104708172;9408.76478088026;9427.811443770948;10340.172786176938;10457.947710261704;10577.788444230919;10588.364698118137;10599.364038157735;8918.331125165769;8947.657851322354;9041.367104302899;9103.544822758704;9168.816623667622;8613.48319100856;8775.210231079234;8911.688090916787;8927.821443570961;9129.406588571721;8117.228863257981;8119.675212991414;8192.098869124273;8215.79460513468;8223.917760822314;6004.219451471281;6035.909461358171;6072.362401947908;6124.265088189446;6178.721957511832;5946.375630558729;6070.484008859296;6100.1154832548145;6146.354632763496;6217.222847144107 15:16:12 INFO - PID 2523 | |4;prototype.html;337.979721216727;357.8640116755633;393.9409088636703;399.7082130045069;405.4668111433463;4941.431735350436;5962.880742385155;5976.3724808895095;5977.282726072877;5992.910106348409;5648.265725455685;5736.196932429453;5809.302883653957;5822.961963312839;5830.959164292495;3995.40068989651;4204.97897510512;4226.704130710851;4436.667249956258;4465.151621191963;3914.7063970202207;4456.509783923766;4465.419495465585;4470.843520476784;4525.185466616008;3812.5043744313202;4355.782210889459;4427.88930276744;4450.866474005768;4459.286514157736;3999.94001589579;4418.64650827933;4418.977905110486;4433.69206084206;4459.375687403766;3394.389009978203;3615.2769446110883;3928.292907276689;3954;3955.46601208836;4808.3989501312335;5352.036633405985;5503.422140675251;5714.771409143629;5735.569832262597;4697.67116301859;4971.403431588196;5127.9230811537855;5317.601179911483;5394.460553944614;5176.818811341585;6200;6281.623102613858;6288.654124023177;6299.401556852092;3797.39241721326;4398.956010439919;4495.325701144854;4506.459224893034;4514.4582650081875;6401.199850018747;6526.869462610721;6540.4767618590395;6608.702608382682;6615.735370585219;4572.565606267399;4675.579197872208;4676.672632915731;4683.859484215479;4686.789094490756;4510;4759.428868535798;4763.570579004732;4778.5699287064335;4788.688735232199;5069.847904562869;5348.545373643284;5371.677699338053;5417.214503896913;5423.857576257356;5053.519915608012;6361.936380636134;6377.585456945377;6380.585261957958;6398.232212134573;1280.5785578008404;1585.3658536585458;1585.9207039647972;1607.9196040197944;1626.926788294506;956.5456408206046;1114.6600286912408;1134.5688638317386;1135.7047167736448;1135.971600709989;5083.567896728746;5963.2844058713235;5978.1929439525975;5981;5991.340952495222;6027.336992930774;6531.444827189647;6559.573627714183;6573.638449885302;6578;5142;5459.062023005502;5869.5010924071075;5959.682736299536;6006.489448396936;2912.8834846606323;3122.235936695805;3359.3617212729505;3408.3183363327435;3411.0045623310143;6522.5760325578685;6679.365460281363;6713.295104014106;6773.1891265125405;6799.456043516507;5584.179989105476;6693.531452798355;6706.26231114577;6717.5297729159465;6728.293529179366;6394.616323020634;6721.563098398687;6738.932610673831;6753.2856928689025;6845.589264644137;6527.2167339919515;6569.343065693392;6591.901121483085;6603.141591593062;6605.702743376463;6053.335332783519;6177.498113264135;6275.592086514362;6467.741290348334;6485.935140648533;5293.770799812189;5920.970395147997;6750.932490675031;6776.595213111517;6850.691718872563;3533.2933413316923;3589.8384572694813;3822.311983842824;4212.178625168062;4263.67825973949;5992.790252341147;6318.557700960889;6600;6601.240857301472;6611.07444957716;5602.411746766531;6138.8643101026455;6395.491178719085;6400.295967443484;6418.133551970611;6005.258475042189;6276.121343012073;6340;6397.104405383157;6427.935720642734;5136.637146912462;5714.685692286798;6181.660008699565;6207.131001659681;6233.501319894307;5345.743750218618;5789.652620842763;6229.470495007794;6270.843228919313;6330.461910737639;5124.8718782029255;5340.305760251298;5403.783848646168;5423.83387571674;6004 15:16:12 INFO - PID 2523 | |5;yui.html;799.4324029938743;832.4672209785742;839.127307600096;842.4987132656071;858.6694122762736;16171.514854554352;16364.990577308561;16782.07698576578;16787.328506859725;16792.748108778364;15384.38462461503;15419;15469.226538673078;15548.76676849848;15554.377824887019;980.9803803923917;1000;1002.0480543483683;1002.6340385759182;1003.5534187286661;973.6300205921775;994.9850752238686;996.2229461020379;997.536145692253;1002.8746406699163;1052.2844465763278;1059.2108878885203;1059.284982636721;1060.4538662588773;1062.2404980438985;1048.5281623269484;1054.6150655010904;1054.620336678795;1055.003022144074;1066.3601838896639;781.1368437876122;782.6634547144726;784.619459562112;786.5791801386231;787.5510958753513;16073;16158.030518168947;16680;17049.14754262282;17221.225044872885;17151.57121071983;17321.826781732147;17438.564035899202;17611.823881761145;17685.646287074185;13911.165330080228;13984.160950343012;13998.510052148125;14008.439662413492;14258.14451132925;3475.2701932594186;3493.5458390409085;3493.598236202844;3497.090756403353;3498.807565583892;113819.99999999917;114046;114419;116297;117043.82956170414;13175.07774455779;13238.53665121716;13359.398827052806;13378.197308161542;13380.397882095329;25131.748682513306;25183;25513.23460296194;25876.35309117287;25972.35069123287;7638.847223055508;7716.382689384835;7718.3053525183;7719.150893401721;7727.729529466442;3829.3681542545723;3861.806909654506;3918.804059796999;3922.2743792398496;4026.516817981861;4204.600562946515;4306.784660766949;4311;4315.438992930962;4321.336285530094;905.1310741688053;911.8677791720163;912.2382810353429;919.2324409118461;923.50592433048;595.7319206357157;609.1228630771675;630.6562923206864;632.253940350391;639.536336156283;11214;11617.361045142598;11645.184837061324;11760.473656598897;12061.457234424472;630.8549033722178;649.9155109835691;653.4511010750897;661.4344735251387;686.993130068703;4490.281554951257;5504.119340905436;5504.339479262434;5583.078791999355;5626.2967129108865;104396.47801760942;104953;105430.4728476368;106270;109131;81182.59408703037;81509.59245203855;82070.17929820745;83571.3285734282;90605.54697226471;77261.45477090427;77989.61005194938;78192;79966;80212;11129.332240065622;11455.369954652575;11466;11542.480588373712;11703;36204.09489762777;38529.614703853724;38895.80552097334;39356.80321598374;40824.38763418492;52095.87663093816;52557.160499382335;69251.65374173097;71071.93392099226;72014;15929.920350398637;16853.1573421326;16990.235439405504;17032.48902532926;17089.74365384545;18200.72698909491;18511;18734.906325468284;18763.718544221574;18782.342618008304;16931.91534042322;16950.830491694924;16962.83037169662;17240.74138887943;17285.395011174547;14650;16286.267117979956;17227.827721723123;17363.565910851823;17532.123393830003;15270.847291526943;15495.922520387327;16240.269187886337;17116.914415428342;17287.654246915245;10798.784024319313;11400.828987565359;11551;11762.411879405825;12045.75908481843;12123.211991220542;12430.564930227398;12536.247825130522;12779.297138657463;13143.277119758504 15:16:12 INFO - PID 2523 | __end_tp_report 15:16:12 INFO - PID 2523 | __start_cc_report 15:16:12 INFO - PID 2523 | _x_x_mozilla_cycle_collect,1592 15:16:12 INFO - PID 2523 | __end_cc_report 15:16:12 INFO - PID 2523 | __startTimestamp1493849772549__endTimestamp 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | ------- Summary: start ------- 15:16:12 INFO - PID 2523 | Number of tests: 6 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#0] dojo.html Cycles:165 Average:6024.33 Median:6273.81 stddev:689.24 (11.0%) stddev-sans-first:662.24 15:16:12 INFO - PID 2523 | Values: 3498.0 3514.5 3517.7 3539.9 3574.1 5016.4 6125.8 6169.7 6182.9 6215.3 6108.1 6110.9 6182.4 6194.5 6204.0 5187.5 5231.3 5306.3 5330.4 5364.5 5419.4 5423.3 5425.8 5435.8 5456.0 5207.5 5302.6 5324.4 5373.5 5405.1 5322.7 5386.6 5388.6 5405.9 5426.7 5290.5 5332.9 5335.7 5348.9 5357.5 6048.8 6158.7 6175.6 6180.8 6187.8 6324.9 6353.8 6401.8 6490.7 6515.4 5683.5 6601.1 6627.0 6628.9 6637.1 6209.5 6210.9 6238.2 6273.8 6273.9 6493.8 6592.9 6659.5 6756.8 6785.5 6355.0 6360.1 6364.3 6367.6 6374.3 6456.2 6588.9 6591.5 6594.4 6618.2 5503.8 6249.8 6322.2 6454.6 6487.9 5502.6 6436.0 6478.0 6491.0 6505.5 5251.8 5261.4 5263.6 5271.9 5298.0 4613.9 4710.3 4721.0 4731.4 4747.0 6172.7 6175.6 6178.6 6234.9 6274.2 6569.3 6572.2 6611.8 6616.6 6622.7 6256.5 6265.4 6278.7 6284.0 6284.7 5598.1 5601.5 5736.6 5748.1 5825.5 6645.0 6651.7 6655.7 6658.4 6665.3 6426.8 6434.3 6436.1 6444.5 6473.3 6808.7 6813.9 6817.1 6830.3 6832.2 6465.7 6479.6 6497.7 6498.4 6501.6 6371.6 6463.8 6472.1 6604.9 6607.3 6369.1 6369.6 6372.6 6378.9 6392.1 6369.9 6383.5 6384.8 6386.6 6395.9 5307.9 6167.5 6226.7 6268.3 6275.1 6337.3 6368.3 6381.7 6382.6 6383.4 6228.8 6245.1 6247.4 6249.2 6250.9 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#1] ext.html Cycles:180 Average:42948.05 Median:157459.28 stddev:76386.46 (48.5%) stddev-sans-first:76535.12 15:16:12 INFO - PID 2523 | Values: 778.2 1076.7 1091.4 1101.1 1214.9 33976.7 35586.5 35611.8 35710.0 35772.6 35716.5 36020.8 36057.8 36069.8 36081.8 5838.3 5931.6 5945.7 7453.7 7595.6 5835.9 5904.6 7907.8 8062.9 8066.0 7086.5 8084.8 8114.4 8129.0 8146.5 8054.2 8097.3 8098.6 8130.2 8135.9 5799.7 5899.5 7816.5 7955.8 7967.6 35426.4 35576.0 35637.5 35729.3 35804.6 3573.3 3620.9 3621.6 3628.7 3628.9 29110.3 29151.6 29163.6 29204.3 29264.0 3072.2 3129.6 3130.0 3151.7 3161.0 291964.0 298247.0 298636.0 298896.0 299468.5 37362.6 37700.2 43528.9 44796.9 47033.8 41876.4 44449.0 47003.5 47116.5 47185.3 13461.2 13574.9 13578.4 13593.7 13595.9 11034.0 11039.4 11047.8 11061.7 11136.8 11903.5 11949.5 11960.3 11976.8 12114.5 4372.9 5041.9 5062.9 5097.5 5107.9 999.0 1000.8 1007.9 1021.9 1029.9 20917.4 22758.4 23528.8 23550.6 23572.0 1092.1 1097.3 1133.4 1137.4 1147.5 11748.7 11971.8 12040.6 12051.9 12068.0 265064.6 310864.0 311322.0 311485.0 311789.0 50115.2 50116.5 50246.0 50249.7 50285.5 1426.2 1462.2 1506.6 1510.2 1534.3 23137.7 23158.9 23232.4 23246.4 23343.3 36499.3 36515.5 36573.8 36598.6 36658.6 271071.0 271176.0 271942.0 272602.0 272927.3 28309.3 28538.3 31491.4 32360.5 32562.0 33428.7 40626.8 41451.8 41497.0 41682.6 26633.6 27217.6 29226.0 29245.6 29758.9 28762.4 29644.7 29665.6 30017.7 30095.2 34385.3 35128.3 35614.3 35999.1 36249.0 28700.0 29407.4 30189.4 30251.4 30407.7 20974.9 22215.8 22543.0 23542.4 23603.2 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#2] jquery.html Cycles:180 Average:35185.42 Median:22134.73 stddev:93012.11 (420.2%) stddev-sans-first:93236.40 15:16:12 INFO - PID 2523 | Values: 419.1 434.2 434.5 437.6 450.4 19064.4 20495.8 20940.4 21132.9 21155.9 21180.7 21516.6 21833.3 21841.3 22545.0 5371.6 5941.5 6209.7 6228.8 6238.8 5928.9 6090.5 6170.2 6192.8 6220.4 5398.6 6020.0 6186.3 6201.6 6202.4 6062.0 6205.4 6213.9 6218.8 6222.0 5193.4 5346.7 5533.2 5535.1 5540.7 27294.9 27604.9 27800.3 28306.0 28353.6 2181.6 2260.4 2261.4 2263.0 2276.2 17247.3 17253.8 17540.5 17671.3 17849.0 2092.7 2095.1 2114.1 2121.8 2123.3 142558.3 158777.0 160457.0 162166.2 164106.0 11404.6 11959.6 11983.0 12006.3 12055.3 16093.0 16105.5 16157.9 16158.8 16193.2 8592.4 8824.0 8827.9 8931.6 9012.3 6970.5 8351.0 8527.1 8569.5 8570.9 6167.9 7557.6 7588.7 7604.5 7620.8 1807.0 1863.9 2058.9 2114.6 2248.0 941.3 953.9 959.0 981.2 988.4 17406.4 18211.6 20055.9 20643.3 21443.0 977.1 997.9 1004.7 1017.7 1020.6 10144.3 10582.9 10622.7 10669.3 10690.8 537150.0 541470.0 544825.0 559888.0 560499.0 120710.6 120902.0 121269.8 123480.0 124110.0 1146.9 1176.2 1249.2 1260.5 1267.5 17803.6 21894.7 22122.7 22127.9 22141.6 43570.9 44744.3 54421.0 55225.7 57392.0 75862.9 76656.0 76991.6 77677.0 78735.2 8695.8 8765.1 8881.6 9047.8 9126.4 13838.9 13987.0 13999.3 14378.1 14388.4 11785.6 11786.2 11989.5 12073.9 12221.4 11153.7 11181.7 11309.6 11499.8 11559.4 11860.4 12088.5 12359.9 12399.0 12482.9 8150.4 8589.4 8919.4 9049.4 9079.9 11228.9 11311.8 11556.9 11621.4 11666.9 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#3] mootools.html Cycles:180 Average:7099.39 Median:3933.30 stddev:4509.86 (114.7%) stddev-sans-first:4500.71 15:16:12 INFO - PID 2523 | Values: 1199.2 1333.3 1422.6 1435.8 1466.0 9140.3 9466.8 11378.5 11595.7 11621.5 11222.2 11335.3 11336.5 11341.4 11371.0 2464.5 2514.3 2514.3 2516.5 2517.3 2114.2 2179.3 2381.5 2534.5 2543.2 2450.9 2471.3 2474.5 2476.4 2476.9 2152.5 2449.9 2498.7 2499.7 2502.5 2442.8 2474.3 2487.2 2492.8 2493.0 11190.7 11436.5 11459.7 11466.2 11493.2 6407.1 6647.8 6657.7 6669.7 6683.7 3904.3 4070.7 4140.5 4161.4 4212.9 1101.7 1229.1 1273.3 1276.9 1284.7 16326.8 16329.9 16357.8 16364.2 16387.3 5517.9 5790.9 5792.4 5909.0 5942.6 13534.3 13552.4 13574.3 13577.5 13588.9 6625.4 6637.4 6637.9 6647.0 6652.5 5859.7 6042.9 6104.1 6121.6 6122.4 3279.7 3929.7 3936.9 3939.5 3945.8 1233.4 1243.8 1244.7 1244.8 1250.8 796.0 799.1 817.1 823.7 847.6 6417.9 6766.2 6975.1 6983.9 6994.1 928.2 933.0 940.1 940.4 944.4 4070.8 4223.6 4375.2 4411.9 4516.4 14524.2 14829.8 15088.2 15108.0 15115.4 14291.2 14641.1 14891.5 14972.3 14984.9 7396.8 8457.7 8566.7 8613.3 8706.3 6944.1 6987.4 7121.6 7150.2 7154.1 12820.0 13065.5 13178.7 13607.9 13617.8 13417.5 13719.0 13831.7 13878.4 14238.7 9268.8 9293.4 9398.4 9408.8 9427.8 10340.2 10457.9 10577.8 10588.4 10599.4 8918.3 8947.7 9041.4 9103.5 9168.8 8613.5 8775.2 8911.7 8927.8 9129.4 8117.2 8119.7 8192.1 8215.8 8223.9 6004.2 6035.9 6072.4 6124.3 6178.7 5946.4 6070.5 6100.1 6146.4 6217.2 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#4] prototype.html Cycles:180 Average:5118.34 Median:5593.30 stddev:1582.23 (28.3%) stddev-sans-first:1545.45 15:16:12 INFO - PID 2523 | Values: 338.0 357.9 393.9 399.7 405.5 4941.4 5962.9 5976.4 5977.3 5992.9 5648.3 5736.2 5809.3 5823.0 5831.0 3995.4 4205.0 4226.7 4436.7 4465.2 3914.7 4456.5 4465.4 4470.8 4525.2 3812.5 4355.8 4427.9 4450.9 4459.3 3999.9 4418.6 4419.0 4433.7 4459.4 3394.4 3615.3 3928.3 3954.0 3955.5 4808.4 5352.0 5503.4 5714.8 5735.6 4697.7 4971.4 5127.9 5317.6 5394.5 5176.8 6200.0 6281.6 6288.7 6299.4 3797.4 4399.0 4495.3 4506.5 4514.5 6401.2 6526.9 6540.5 6608.7 6615.7 4572.6 4675.6 4676.7 4683.9 4686.8 4510.0 4759.4 4763.6 4778.6 4788.7 5069.8 5348.5 5371.7 5417.2 5423.9 5053.5 6361.9 6377.6 6380.6 6398.2 1280.6 1585.4 1585.9 1607.9 1626.9 956.5 1114.7 1134.6 1135.7 1136.0 5083.6 5963.3 5978.2 5981.0 5991.3 6027.3 6531.4 6559.6 6573.6 6578.0 5142.0 5459.1 5869.5 5959.7 6006.5 2912.9 3122.2 3359.4 3408.3 3411.0 6522.6 6679.4 6713.3 6773.2 6799.5 5584.2 6693.5 6706.3 6717.5 6728.3 6394.6 6721.6 6738.9 6753.3 6845.6 6527.2 6569.3 6591.9 6603.1 6605.7 6053.3 6177.5 6275.6 6467.7 6485.9 5293.8 5921.0 6750.9 6776.6 6850.7 3533.3 3589.8 3822.3 4212.2 4263.7 5992.8 6318.6 6600.0 6601.2 6611.1 5602.4 6138.9 6395.5 6400.3 6418.1 6005.3 6276.1 6340.0 6397.1 6427.9 5136.6 5714.7 6181.7 6207.1 6233.5 5345.7 5789.7 6229.5 6270.8 6330.5 5124.9 5340.3 5403.8 5423.8 6004.0 15:16:12 INFO - PID 2523 | 15:16:12 INFO - PID 2523 | [#5] yui.html Cycles:180 Average:21391.05 Median:17401.06 stddev:29498.34 (169.5%) stddev-sans-first:29540.57 15:16:12 INFO - PID 2523 | Values: 799.4 832.5 839.1 842.5 858.7 16171.5 16365.0 16782.1 16787.3 16792.7 15384.4 15419.0 15469.2 15548.8 15554.4 981.0 1000.0 1002.0 1002.6 1003.6 973.6 995.0 996.2 997.5 1002.9 1052.3 1059.2 1059.3 1060.5 1062.2 1048.5 1054.6 1054.6 1055.0 1066.4 781.1 782.7 784.6 786.6 787.6 16073.0 16158.0 16680.0 17049.1 17221.2 17151.6 17321.8 17438.6 17611.8 17685.6 13911.2 13984.2 13998.5 14008.4 14258.1 3475.3 3493.5 3493.6 3497.1 3498.8 113820.0 114046.0 114419.0 116297.0 117043.8 13175.1 13238.5 13359.4 13378.2 13380.4 25131.7 25183.0 25513.2 25876.4 25972.4 7638.8 7716.4 7718.3 7719.2 7727.7 3829.4 3861.8 3918.8 3922.3 4026.5 4204.6 4306.8 4311.0 4315.4 4321.3 905.1 911.9 912.2 919.2 923.5 595.7 609.1 630.7 632.3 639.5 11214.0 11617.4 11645.2 11760.5 12061.5 630.9 649.9 653.5 661.4 687.0 4490.3 5504.1 5504.3 5583.1 5626.3 104396.5 104953.0 105430.5 106270.0 109131.0 81182.6 81509.6 82070.2 83571.3 90605.5 77261.5 77989.6 78192.0 79966.0 80212.0 11129.3 11455.4 11466.0 11542.5 11703.0 36204.1 38529.6 38895.8 39356.8 40824.4 52095.9 52557.2 69251.7 71071.9 72014.0 15929.9 16853.2 16990.2 17032.5 17089.7 18200.7 18511.0 18734.9 18763.7 18782.3 16931.9 16950.8 16962.8 17240.7 17285.4 14650.0 16286.3 17227.8 17363.6 17532.1 15270.8 15495.9 16240.3 17116.9 17287.7 10798.8 11400.8 11551.0 11762.4 12045.8 12123.2 12430.6 12536.2 12779.3 13143.3 15:16:12 INFO - PID 2523 | -------- Summary: end -------- 15:16:12 INFO - PID 2523 | 15:16:13 INFO - TEST-INFO | 2523: exit 0 15:16:13 INFO - TEST-OK | dromaeo_css | took 1119046ms 15:16:13 INFO - TEST-START | kraken 15:16:13 INFO - operating with platform_type : mac_ 15:16:13 INFO - Initialising browser for kraken test... 15:16:13 INFO - TEST-INFO | started process 2527 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpn4h4WI/profile http://localhost:60097/getInfo.html) 15:16:15 INFO - PID 2527 | __metrics Screen width/height:1600/1200 15:16:15 INFO - PID 2527 | colorDepth:24 15:16:15 INFO - PID 2527 | Browser inner width/height: 1024/691 15:16:15 INFO - PID 2527 | __metrics 15:16:15 INFO - PID 2527 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:16:15 INFO - PID 2527 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:16:16 INFO - TEST-INFO | 2527: exit 0 15:16:16 INFO - Browser initialized. 15:16:16 INFO - Running cycle 1/1 for kraken test... 15:16:16 INFO - TEST-INFO | started process 2528 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpn4h4WI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:16:28 INFO - PID 2528 | RSS: Main: 240300032 15:16:28 INFO - PID 2528 | 15:16:31 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-beat-detection) 15:16:32 INFO - PID 2528 | RSS: Main: 308850688 15:16:32 INFO - PID 2528 | 15:16:34 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-dft) 15:16:35 INFO - PID 2528 | RSS: Main: 292347904 15:16:35 INFO - PID 2528 | 15:16:38 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-fft) 15:16:39 INFO - PID 2528 | RSS: Main: 382046208 15:16:39 INFO - PID 2528 | 15:16:41 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60097/tests/kraken/driver.html?testName=audio-oscillator) 15:16:41 INFO - PID 2528 | RSS: Main: 283852800 15:16:42 INFO - PID 2528 | 15:16:43 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:16:44 INFO - PID 2528 | RSS: Main: 286142464 15:16:44 INFO - PID 2528 | 15:16:47 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-darkroom) 15:16:48 INFO - PID 2528 | RSS: Main: 349941760 15:16:48 INFO - PID 2528 | 15:16:50 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60097/tests/kraken/driver.html?testName=imaging-desaturate) 15:16:51 INFO - PID 2528 | RSS: Main: 368435200 15:16:51 INFO - PID 2528 | 15:16:54 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60097/tests/kraken/driver.html?testName=json-parse-financial) 15:16:54 INFO - PID 2528 | RSS: Main: 377155584 15:16:54 INFO - PID 2528 | 15:16:56 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:16:57 INFO - PID 2528 | RSS: Main: 328663040 15:16:57 INFO - PID 2528 | 15:16:58 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:16:59 INFO - PID 2528 | RSS: Main: 333271040 15:16:59 INFO - PID 2528 | 15:17:01 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:17:02 INFO - PID 2528 | RSS: Main: 335486976 15:17:02 INFO - PID 2528 | 15:17:04 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:17:05 INFO - PID 2528 | RSS: Main: 351338496 15:17:05 INFO - PID 2528 | 15:17:08 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:17:09 INFO - PID 2528 | RSS: Main: 344440832 15:17:09 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | Cycle 1(1): loaded http://localhost:60097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60097/tests/kraken/driver.html?testName=ai-astar) 15:17:11 INFO - PID 2528 | RSS: Main: 387137536 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | __start_tp_report 15:17:11 INFO - PID 2528 | _x_x_mozilla_page_load 15:17:11 INFO - PID 2528 | _x_x_mozilla_page_load_details 15:17:11 INFO - PID 2528 | |i|pagename|runs| 15:17:11 INFO - PID 2528 | |0;ai-astar;111;110;109;111;111;111;109;111;111;110 15:17:11 INFO - PID 2528 | |1;audio-beat-detection;138;110;107;150;109;107;108;105;142;130 15:17:11 INFO - PID 2528 | |2;audio-dft;188;187;183;184;185;184;183;184;186;180 15:17:11 INFO - PID 2528 | |3;audio-fft;81;59;57;91;57;59;56;56;81;56 15:17:11 INFO - PID 2528 | |4;audio-oscillator;85;71;86;70;67;68;89;68;70;89 15:17:11 INFO - PID 2528 | |5;imaging-gaussian-blur;88;88;88;87;88;88;88;87;87;88 15:17:11 INFO - PID 2528 | |6;imaging-darkroom;95;96;95;95;95;96;95;94;96;95 15:17:11 INFO - PID 2528 | |7;imaging-desaturate;83;83;84;86;85;84;86;87;84;86 15:17:11 INFO - PID 2528 | |8;json-parse-financial;49;49;50;50;52;50;50;50;49;49 15:17:11 INFO - PID 2528 | |9;json-stringify-tinderbox;54;54;56;63;63;58;56;57;58;57 15:17:11 INFO - PID 2528 | |10;stanford-crypto-aes;82;83;82;80;78;81;82;80;81;77 15:17:11 INFO - PID 2528 | |11;stanford-crypto-ccm;138;135;134;130;134;134;137;136;186;141 15:17:11 INFO - PID 2528 | |12;stanford-crypto-pbkdf2;227;222;184;182;185;186;182;183;181;182 15:17:11 INFO - PID 2528 | |13;stanford-crypto-sha256-iterative;67;68;68;67;66;67;66;66;69;67 15:17:11 INFO - PID 2528 | __end_tp_report 15:17:11 INFO - PID 2528 | __start_cc_report 15:17:11 INFO - PID 2528 | _x_x_mozilla_cycle_collect,2172 15:17:11 INFO - PID 2528 | __end_cc_report 15:17:11 INFO - PID 2528 | __startTimestamp1493849831443__endTimestamp 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | ------- Summary: start ------- 15:17:11 INFO - PID 2528 | Number of tests: 14 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:0.84 (0.8%) stddev-sans-first:0.87 15:17:11 INFO - PID 2528 | Values: 111.0 110.0 109.0 111.0 111.0 111.0 109.0 111.0 111.0 110.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#1] audio-beat-detection Cycles:10 Average:120.60 Median:120.00 stddev:17.42 (14.5%) stddev-sans-first:17.31 15:17:11 INFO - PID 2528 | Values: 138.0 110.0 107.0 150.0 109.0 107.0 108.0 105.0 142.0 130.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.50 stddev:2.27 (1.2%) stddev-sans-first:2.00 15:17:11 INFO - PID 2528 | Values: 188.0 187.0 183.0 184.0 185.0 184.0 183.0 184.0 186.0 180.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#3] audio-fft Cycles:10 Average:65.30 Median:59.00 stddev:13.46 (22.8%) stddev-sans-first:13.02 15:17:11 INFO - PID 2528 | Values: 81.0 59.0 57.0 91.0 57.0 59.0 56.0 56.0 81.0 56.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:78.00 stddev:9.57 (12.3%) stddev-sans-first:9.62 15:17:11 INFO - PID 2528 | Values: 85.0 71.0 86.0 70.0 67.0 68.0 89.0 68.0 70.0 89.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:17:11 INFO - PID 2528 | Values: 88.0 88.0 88.0 87.0 88.0 88.0 88.0 87.0 87.0 88.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 15:17:11 INFO - PID 2528 | Values: 95.0 96.0 95.0 95.0 95.0 96.0 95.0 94.0 96.0 95.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.32 15:17:11 INFO - PID 2528 | Values: 83.0 83.0 84.0 86.0 85.0 84.0 86.0 87.0 84.0 86.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:0.92 (1.8%) stddev-sans-first:0.93 15:17:11 INFO - PID 2528 | Values: 49.0 49.0 50.0 50.0 52.0 50.0 50.0 50.0 49.0 49.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:57.50 stddev:3.17 (5.5%) stddev-sans-first:3.08 15:17:11 INFO - PID 2528 | Values: 54.0 54.0 56.0 63.0 63.0 58.0 56.0 57.0 58.0 57.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.50 stddev:1.90 (2.3%) stddev-sans-first:1.94 15:17:11 INFO - PID 2528 | Values: 82.0 83.0 82.0 80.0 78.0 81.0 82.0 80.0 81.0 77.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:136.50 stddev:16.25 (11.9%) stddev-sans-first:17.21 15:17:11 INFO - PID 2528 | Values: 138.0 135.0 134.0 130.0 134.0 134.0 137.0 136.0 186.0 141.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.40 Median:184.50 stddev:17.55 (9.5%) stddev-sans-first:13.06 15:17:11 INFO - PID 2528 | Values: 227.0 222.0 184.0 182.0 185.0 186.0 182.0 183.0 181.0 182.0 15:17:11 INFO - PID 2528 | 15:17:11 INFO - PID 2528 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 15:17:11 INFO - PID 2528 | Values: 67.0 68.0 68.0 67.0 66.0 67.0 66.0 66.0 69.0 67.0 15:17:11 INFO - PID 2528 | -------- Summary: end -------- 15:17:11 INFO - PID 2528 | 15:17:12 INFO - TEST-INFO | 2528: exit 0 15:17:12 INFO - TEST-OK | kraken | took 59440ms 15:17:12 INFO - SUITE-END | took 1178s 15:17:13 INFO - Completed test suite (00:19:38) 15:17:13 INFO - javascript benchmark 15:17:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7710.11310528254, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3497.9825100874477, 3514.5079688843553, 3517.6834084932357, 3539.893803185905, 3574.0886074051114, 5016.4231113421965, 6125.846853828645, 6169.722362493687, 6182.907256391152, 6215.347388524207, 6108.06889217957, 6110.892920173598, 6182.443580077792, 6194.504439644829, 6204.0, 5187.481251874801, 5231.346081739783, 5306.257124002624, 5330.440303768107, 5364.490373414539, 5419.403865574784, 5423.267858839065, 5425.782968681248, 5435.755391007414, 5455.9633669602645, 5207.505286997729, 5302.628815982883, 5324.41431442541, 5373.516383525502, 5405.135178371462, 5322.7072511011875, 5386.649867758583, 5388.568914486832, 5405.891882162374, 5426.701531415771, 5290.523852853262, 5332.866678333073, 5335.706536140511, 5348.893022139536, 5357.544608708265, 6048.7882924097885, 6158.6612736299485, 6175.59858609189, 6180.752769889199, 6187.752489900398, 6324.873502529969, 6353.809385718436, 6401.8399540011405, 6490.675466226717, 6515.381038801306, 5683.54531637468, 6601.108850305243, 6626.97281921303, 6628.900566491507, 6637.070810086591, 6209.5032397408095, 6210.937890621126, 6238.189035425411, 6273.811785646347, 6273.905891411632, 6493.805185844432, 6592.868142637216, 6659.467242620579, 6756.831079223059, 6785.491088168308, 6355.0, 6360.109584658151, 6364.331745166784, 6367.649779262185, 6374.330695277022, 6456.192975878015, 6588.901166482507, 6591.505637077238, 6594.406503414716, 6618.205815302194, 5503.834884953458, 6249.75000999964, 6322.241331040304, 6454.580452270588, 6487.870242595121, 5502.642328248651, 6435.967820160869, 6478.028295755675, 6490.967545162339, 6505.544611877218, 5251.842444726588, 5261.394939581921, 5263.605229607795, 5271.894562108736, 5298.0, 4613.930791038137, 4710.3405523226775, 4720.976395118002, 4731.384919960383, 4746.976265118652, 6172.722227499773, 6175.567710260329, 6178.62928224308, 6234.875302494015, 6274.247090349096, 6569.310222426672, 6572.244191917991, 6611.834704132339, 6616.636085015274, 6622.701978410983, 6256.499480041586, 6265.373462653698, 6278.748850045947, 6284.0, 6284.654344011124, 5598.10430331145, 5601.495865372136, 5736.569757268222, 5748.051571490657, 5825.533957283407, 6645.0, 6651.700673469609, 6655.73377064912, 6658.43403310724, 6665.333466653296, 6426.807195784134, 6434.292227855026, 6436.066770318372, 6444.45222156122, 6473.255575608865, 6808.7276508939085, 6813.8977915330315, 6817.147856517935, 6830.31696830313, 6832.248452670302, 6465.709043092978, 6479.611223326616, 6497.707603157774, 6498.350164983463, 6501.642409667514, 6371.585846919936, 6463.838404039937, 6472.061551074974, 6604.900926486011, 6607.339266073546, 6369.108324834619, 6369.649669268235, 6372.617642941438, 6378.87242255143, 6392.105105285171, 6369.936300636934, 6383.489320854228, 6384.776532821329, 6386.648734319657, 6395.872082558416, 5307.920381194363, 6167.475764560063, 6226.688665566794, 6268.341824108403, 6275.121482992294, 6337.271213810471, 6368.299487056327, 6381.680915954276, 6382.585131966407, 6383.361663833765, 6228.844278893064, 6245.063240513778, 6247.375262473716, 6249.218847644044, 6250.874982500415], "value": 5980.203069222455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.2178910195253, 1076.6554702495202, 1091.3506463654128, 1101.053094338869, 1214.8602910665277, 33976.66023339769, 35586.466203006945, 35611.82194089026, 35710.0, 35772.64227357729, 35716.464253036225, 36020.819895900546, 36057.81971090147, 36069.81965090177, 36081.81959090201, 5838.2702162229725, 5931.584789064767, 5945.672987985659, 7453.739119130818, 7595.620218989028, 5835.854103647422, 5904.55715821313, 7907.802304942394, 8062.879056814152, 8066.03207615087, 7086.503944723871, 8084.7574572762915, 8114.350851931861, 8129.0, 8146.470479418848, 8054.23484768946, 8097.2712455879255, 8098.554579498125, 8130.227628375294, 8135.942327497417, 5799.739011744482, 5899.469047785677, 7816.452848300622, 7955.801104972365, 7967.601619918981, 35426.405811738536, 35576.0, 35637.46543801819, 35729.28541429183, 35804.56781728728, 3573.3032058748554, 3620.9094772630633, 3621.6378362163837, 3628.6552777486095, 3628.909277268089, 29110.27224319388, 29151.562726559117, 29163.562546561818, 29204.269893252625, 29263.97576084848, 3072.2319420144963, 3129.5618613394145, 3130.0, 3151.6848315168527, 3160.952585711216, 291964.0, 298247.0, 298636.0, 298896.0, 299468.5026574875, 37362.62637373646, 37700.245995079946, 43528.91177720519, 44796.880077998314, 47033.76483117631, 41876.371854421595, 44449.0, 47003.529964700596, 47116.528834711215, 47185.29222061672, 13461.192328460324, 13574.864251357558, 13578.388972496065, 13593.660158496117, 13595.932020340033, 11034.0, 11039.448027598588, 11047.83428248577, 11061.668149955514, 11136.777264454828, 11903.464344104535, 11949.462274197682, 11960.282383057045, 11976.820347694791, 12114.454849531792, 4372.934405983913, 5041.949580504148, 5062.949370506248, 5097.515736005073, 5107.948920510822, 998.9550470228712, 1000.7648202672366, 1007.9193664506822, 1021.933574317667, 1029.8815636201932, 20917.37247882566, 22758.38313040087, 23528.764712353, 23550.64674029891, 23572.0, 1092.0553721031251, 1097.3032124600777, 1133.4332833583208, 1137.4199158429096, 1147.5008371358485, 11748.709001744748, 11971.820422693667, 12040.578579749666, 12051.939740301243, 12068.0345572354, 265064.58966565254, 310864.0, 311322.0, 311485.0, 311789.0, 50115.24827127596, 50116.49883501118, 50246.0, 50249.74875125601, 50285.49714502808, 1426.1514398932825, 1462.1884853905972, 1506.6007508010387, 1510.1543135843963, 1534.3018926388465, 23137.652935206323, 23158.88420557954, 23232.419189519722, 23246.418839529146, 23343.299701008997, 36499.27001460009, 36515.452268215464, 36573.81713091524, 36598.634013659524, 36658.63341366552, 271071.0, 271176.0, 271942.0, 272602.0, 272927.2707272902, 28309.319788427372, 28538.286542836595, 31491.37017259688, 32360.514592281605, 32562.0, 33428.665713342554, 40626.79686601548, 41451.7927410361, 41497.0, 41682.58317416787, 26633.600495992963, 27217.59173612437, 29226.0, 29245.582688107326, 29758.85787411049, 28762.424751504434, 29644.703552964194, 29665.555016674334, 30017.699823002364, 30095.24761880883, 34385.31229375448, 35128.29743405169, 35614.287714246086, 35999.100022499646, 36249.0, 28700.0, 29407.411851762416, 30189.396212076073, 30251.394972099995, 30407.69592304137, 20974.895125524275, 22215.777842222018, 22543.0, 23542.411439714146, 23603.173888913807], "value": 16499.681301262477, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.12233672691343, 434.15123433687137, 434.51768536923987, 437.56025194679347, 450.407713856279, 19064.428067157973, 20495.795042049576, 20940.37178884632, 21132.89433552832, 21155.894220528877, 21180.682289765668, 21516.56966860666, 21833.344999649995, 21841.344759657197, 22544.985475653593, 5371.57027437806, 5941.494972927296, 6209.689515524228, 6228.813135605917, 6238.844028899269, 5928.88142237155, 6090.451859332659, 6170.167027451282, 6192.752289908398, 6220.377962203788, 5398.595105367113, 6020.0, 6186.288576813657, 6201.565890387675, 6202.3797620238065, 6062.0, 6205.379462053804, 6213.887278325368, 6218.813435596962, 6222.004479283338, 5193.428722840522, 5346.652467589594, 5533.170024496318, 5535.114381698909, 5540.695261760601, 27294.863525682442, 27604.861975690194, 27800.30499237515, 28306.0, 28353.574696379572, 2181.596404665126, 2260.3670972127666, 2261.3894248552965, 2262.9547409051893, 2276.226083131743, 17247.31010759568, 17253.82746172535, 17540.473785786446, 17671.293148274053, 17849.0, 2092.7384076990375, 2095.1200495791804, 2114.1437717724193, 2121.8408619353454, 2123.256860098953, 142558.28720856432, 158777.0, 160457.0, 162166.1891690534, 164106.0, 11404.600838970762, 11959.641210763692, 11983.041356691443, 12006.339651319087, 12055.336956467478, 16093.0, 16105.516834494983, 16157.919210403872, 16158.838411615969, 16193.190340483165, 8592.44149130317, 8824.0, 8827.911720882837, 8931.642734290555, 9012.27901767857, 6970.477214208955, 8350.958245208734, 8527.062023177445, 8569.528675922884, 8570.871436928452, 6167.907481387782, 7557.6221188940335, 7588.658510367047, 7604.5437273762645, 7620.771376858703, 1806.9638607227782, 1863.9347622833136, 2058.8970551472366, 2114.598226336991, 2247.9550408992054, 941.2987324443334, 953.9475328856979, 958.9760255993516, 981.2199301555281, 988.3625061835077, 17406.39077632277, 18211.635767284846, 20055.899720501595, 20643.260250711803, 21443.035063422183, 977.0913050862766, 997.9172597731398, 1004.7036124343336, 1017.6692574913183, 1020.550957578663, 10144.28989970695, 10582.947085264525, 10622.73443163927, 10669.253152279422, 10690.786184276427, 537150.0, 541470.0, 544825.0, 559888.0, 560499.0, 120710.58578828374, 120902.0, 121269.78730212762, 123480.0, 124110.0, 1146.908247340211, 1176.2413243457922, 1249.2067537113817, 1260.4958016793173, 1267.4549943524373, 17803.643927121127, 21894.67157992663, 22122.668159977937, 22127.89360531999, 22141.557168856856, 43570.910727232076, 44744.32883506685, 54421.0, 55225.72387138039, 57392.0, 75862.86205707029, 76656.0, 76991.61504192444, 77677.0, 78735.21264787507, 8695.826083478421, 8765.123487651184, 8881.644734210819, 9047.773805654648, 9126.361154719107, 13838.930805346312, 13987.020908536304, 13999.300034998412, 14378.065425747292, 14388.424463021362, 11785.587504437304, 11786.174967752175, 11989.460474278503, 12073.939630301793, 12221.38893055326, 11153.72115697114, 11181.72045698864, 11309.604163854226, 11499.770004600028, 11559.422028898689, 11860.406979651156, 12088.516459341528, 12359.938200308941, 12399.0, 12482.875171248172, 8150.4294699370475, 8589.355798315026, 8919.375643705138, 9049.366544341832, 9079.909200907907, 11228.887711122785, 11311.773764524827, 11556.94221528887, 11621.360825154698, 11666.883331166919], "value": 10319.799705121983, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1199.2145144930064, 1333.293354522103, 1422.608782584789, 1435.8492358302371, 1465.9633509162272, 9140.26877849772, 9466.76333091673, 11378.544858205662, 11595.652130436101, 11621.47703353349, 11222.214444988855, 11335.319880807157, 11336.48985795639, 11341.37622430766, 11370.976612104909, 2464.519418713356, 2514.270861450177, 2514.283429222672, 2516.534441128388, 2517.332906779705, 2114.2071723103836, 2179.259051922346, 2381.523695260946, 2534.531111744333, 2543.224316583441, 2450.938726531842, 2471.295680730992, 2474.5298393305306, 2476.4428003699204, 2476.8761561921833, 2152.4726442021647, 2449.9020039198413, 2498.7001559812757, 2499.687539057618, 2502.461970676295, 2442.792362649177, 2474.3319303787875, 2487.1916627096266, 2492.750724927511, 2492.950140997188, 11190.66428007161, 11436.542538298458, 11459.713507162303, 11466.197366184371, 11493.195476316661, 6407.13503677007, 6647.833804154887, 6657.733690652368, 6669.666516674147, 6683.732650693966, 3904.2777086239134, 4070.6743460523385, 4140.4617399737845, 4161.396597493377, 4212.85255016073, 1101.7080473674482, 1229.0781913564826, 1273.3378643105625, 1276.9233845969272, 1284.665986843418, 16326.836731632651, 16329.91835040829, 16357.83642163587, 16364.181790910288, 16387.344506219735, 5517.944820551824, 5790.89790897909, 5792.4497172768515, 5908.970455147697, 5942.61373010753, 13534.255615941021, 13552.357290328291, 13574.321283935764, 13577.456901724017, 13588.93205533966, 6625.370589793962, 6637.435817955431, 6637.867242655217, 6647.0, 6652.467802575782, 5859.736311865976, 6042.939570604326, 6104.053871649901, 6121.571489995657, 6122.387761223931, 3279.6720327967014, 3929.685625149981, 3936.9212615747524, 3939.5075615548058, 3945.8421663133154, 1233.3771445420005, 1243.7512497500536, 1244.7012716948047, 1244.8132780082879, 1250.843644544432, 796.0487217774704, 799.0531220503722, 817.1052697296451, 823.7446391618618, 847.5719761520393, 6417.871642567123, 6766.221884483348, 6975.093237879043, 6983.8603227935155, 6994.090768200102, 928.178561972659, 932.9720108396759, 940.0505489455699, 940.4357385568741, 944.4474982135528, 4070.816813243411, 4223.556526564728, 4375.16871794358, 4411.867643970686, 4516.3902873112365, 14524.20116893581, 14829.77755333671, 15088.17015064182, 15108.0, 15115.395384184729, 14291.213983231022, 14641.121532708072, 14891.47879824201, 14972.326245318987, 14984.925075374553, 7396.815079623052, 8457.746267611981, 8566.742997710078, 8613.267872230805, 8706.347023973101, 6944.131983502062, 6987.371136597731, 7121.572705637678, 7150.177729561167, 7154.069970903957, 12820.0, 13065.542706005244, 13178.670533236746, 13607.86392136066, 13617.79573306421, 13417.463301467831, 13719.0, 13831.654208644864, 13878.444862205399, 14238.715225695636, 9268.814623707623, 9293.395929264576, 9398.389104708172, 9408.76478088026, 9427.811443770948, 10340.172786176938, 10457.947710261704, 10577.788444230919, 10588.364698118137, 10599.364038157735, 8918.331125165769, 8947.657851322354, 9041.367104302899, 9103.544822758704, 9168.816623667622, 8613.48319100856, 8775.210231079234, 8911.688090916787, 8927.821443570961, 9129.406588571721, 8117.228863257981, 8119.675212991414, 8192.098869124273, 8215.79460513468, 8223.917760822314, 6004.219451471281, 6035.909461358171, 6072.362401947908, 6124.265088189446, 6178.721957511832, 5946.375630558729, 6070.484008859296, 6100.1154832548145, 6146.354632763496, 6217.222847144107], "value": 5356.110485621064, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.979721216727, 357.8640116755633, 393.9409088636703, 399.7082130045069, 405.4668111433463, 4941.431735350436, 5962.880742385155, 5976.3724808895095, 5977.282726072877, 5992.910106348409, 5648.265725455685, 5736.196932429453, 5809.302883653957, 5822.961963312839, 5830.959164292495, 3995.40068989651, 4204.97897510512, 4226.704130710851, 4436.667249956258, 4465.151621191963, 3914.7063970202207, 4456.509783923766, 4465.419495465585, 4470.843520476784, 4525.185466616008, 3812.5043744313202, 4355.782210889459, 4427.88930276744, 4450.866474005768, 4459.286514157736, 3999.94001589579, 4418.64650827933, 4418.977905110486, 4433.69206084206, 4459.375687403766, 3394.389009978203, 3615.2769446110883, 3928.292907276689, 3954.0, 3955.46601208836, 4808.3989501312335, 5352.036633405985, 5503.422140675251, 5714.771409143629, 5735.569832262597, 4697.67116301859, 4971.403431588196, 5127.9230811537855, 5317.601179911483, 5394.460553944614, 5176.818811341585, 6200.0, 6281.623102613858, 6288.654124023177, 6299.401556852092, 3797.39241721326, 4398.956010439919, 4495.325701144854, 4506.459224893034, 4514.4582650081875, 6401.199850018747, 6526.869462610721, 6540.4767618590395, 6608.702608382682, 6615.735370585219, 4572.565606267399, 4675.579197872208, 4676.672632915731, 4683.859484215479, 4686.789094490756, 4510.0, 4759.428868535798, 4763.570579004732, 4778.5699287064335, 4788.688735232199, 5069.847904562869, 5348.545373643284, 5371.677699338053, 5417.214503896913, 5423.857576257356, 5053.519915608012, 6361.936380636134, 6377.585456945377, 6380.585261957958, 6398.232212134573, 1280.5785578008404, 1585.3658536585458, 1585.9207039647972, 1607.9196040197944, 1626.926788294506, 956.5456408206046, 1114.6600286912408, 1134.5688638317386, 1135.7047167736448, 1135.971600709989, 5083.567896728746, 5963.2844058713235, 5978.1929439525975, 5981.0, 5991.340952495222, 6027.336992930774, 6531.444827189647, 6559.573627714183, 6573.638449885302, 6578.0, 5142.0, 5459.062023005502, 5869.5010924071075, 5959.682736299536, 6006.489448396936, 2912.8834846606323, 3122.235936695805, 3359.3617212729505, 3408.3183363327435, 3411.0045623310143, 6522.5760325578685, 6679.365460281363, 6713.295104014106, 6773.1891265125405, 6799.456043516507, 5584.179989105476, 6693.531452798355, 6706.26231114577, 6717.5297729159465, 6728.293529179366, 6394.616323020634, 6721.563098398687, 6738.932610673831, 6753.2856928689025, 6845.589264644137, 6527.2167339919515, 6569.343065693392, 6591.901121483085, 6603.141591593062, 6605.702743376463, 6053.335332783519, 6177.498113264135, 6275.592086514362, 6467.741290348334, 6485.935140648533, 5293.770799812189, 5920.970395147997, 6750.932490675031, 6776.595213111517, 6850.691718872563, 3533.2933413316923, 3589.8384572694813, 3822.311983842824, 4212.178625168062, 4263.67825973949, 5992.790252341147, 6318.557700960889, 6600.0, 6601.240857301472, 6611.07444957716, 5602.411746766531, 6138.8643101026455, 6395.491178719085, 6400.295967443484, 6418.133551970611, 6005.258475042189, 6276.121343012073, 6340.0, 6397.104405383157, 6427.935720642734, 5136.637146912462, 5714.685692286798, 6181.660008699565, 6207.131001659681, 6233.501319894307, 5345.743750218618, 5789.652620842763, 6229.470495007794, 6270.843228919313, 6330.461910737639, 5124.8718782029255, 5340.305760251298, 5403.783848646168, 5423.83387571674, 6004.0], "value": 4633.082034060153, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.4324029938743, 832.4672209785742, 839.127307600096, 842.4987132656071, 858.6694122762736, 16171.514854554352, 16364.990577308561, 16782.07698576578, 16787.328506859725, 16792.748108778364, 15384.38462461503, 15419.0, 15469.226538673078, 15548.76676849848, 15554.377824887019, 980.9803803923917, 1000.0, 1002.0480543483683, 1002.6340385759182, 1003.5534187286661, 973.6300205921775, 994.9850752238686, 996.2229461020379, 997.536145692253, 1002.8746406699163, 1052.2844465763278, 1059.2108878885203, 1059.284982636721, 1060.4538662588773, 1062.2404980438985, 1048.5281623269484, 1054.6150655010904, 1054.620336678795, 1055.003022144074, 1066.3601838896639, 781.1368437876122, 782.6634547144726, 784.619459562112, 786.5791801386231, 787.5510958753513, 16073.0, 16158.030518168947, 16680.0, 17049.14754262282, 17221.225044872885, 17151.57121071983, 17321.826781732147, 17438.564035899202, 17611.823881761145, 17685.646287074185, 13911.165330080228, 13984.160950343012, 13998.510052148125, 14008.439662413492, 14258.14451132925, 3475.2701932594186, 3493.5458390409085, 3493.598236202844, 3497.090756403353, 3498.807565583892, 113819.99999999917, 114046.0, 114419.0, 116297.0, 117043.82956170414, 13175.07774455779, 13238.53665121716, 13359.398827052806, 13378.197308161542, 13380.397882095329, 25131.748682513306, 25183.0, 25513.23460296194, 25876.35309117287, 25972.35069123287, 7638.847223055508, 7716.382689384835, 7718.3053525183, 7719.150893401721, 7727.729529466442, 3829.3681542545723, 3861.806909654506, 3918.804059796999, 3922.2743792398496, 4026.516817981861, 4204.600562946515, 4306.784660766949, 4311.0, 4315.438992930962, 4321.336285530094, 905.1310741688053, 911.8677791720163, 912.2382810353429, 919.2324409118461, 923.50592433048, 595.7319206357157, 609.1228630771675, 630.6562923206864, 632.253940350391, 639.536336156283, 11214.0, 11617.361045142598, 11645.184837061324, 11760.473656598897, 12061.457234424472, 630.8549033722178, 649.9155109835691, 653.4511010750897, 661.4344735251387, 686.993130068703, 4490.281554951257, 5504.119340905436, 5504.339479262434, 5583.078791999355, 5626.2967129108865, 104396.47801760942, 104953.0, 105430.4728476368, 106270.0, 109131.0, 81182.59408703037, 81509.59245203855, 82070.17929820745, 83571.3285734282, 90605.54697226471, 77261.45477090427, 77989.61005194938, 78192.0, 79966.0, 80212.0, 11129.332240065622, 11455.369954652575, 11466.0, 11542.480588373712, 11703.0, 36204.09489762777, 38529.614703853724, 38895.80552097334, 39356.80321598374, 40824.38763418492, 52095.87663093816, 52557.160499382335, 69251.65374173097, 71071.93392099226, 72014.0, 15929.920350398637, 16853.1573421326, 16990.235439405504, 17032.48902532926, 17089.74365384545, 18200.72698909491, 18511.0, 18734.906325468284, 18763.718544221574, 18782.342618008304, 16931.91534042322, 16950.830491694924, 16962.83037169662, 17240.74138887943, 17285.395011174547, 14650.0, 16286.267117979956, 17227.827721723123, 17363.565910851823, 17532.123393830003, 15270.847291526943, 15495.922520387327, 16240.269187886337, 17116.914415428342, 17287.654246915245, 10798.784024319313, 11400.828987565359, 11551.0, 11762.411879405825, 12045.75908481843, 12123.211991220542, 12430.564930227398, 12536.247825130522, 12779.297138657463, 13143.277119758504], "value": 8312.891365012712, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1411.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 109.0, 111.0, 111.0, 111.0, 109.0, 111.0, 111.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 107.0, 150.0, 109.0, 107.0, 108.0, 105.0, 142.0, 130.0], "value": 120.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 187.0, 183.0, 184.0, 185.0, 184.0, 183.0, 184.0, 186.0, 180.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 57.0, 91.0, 57.0, 59.0, 56.0, 56.0, 81.0, 56.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 86.0, 70.0, 67.0, 68.0, 89.0, 68.0, 70.0, 89.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 87.0, 88.0, 88.0, 88.0, 87.0, 87.0, 88.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 86.0, 85.0, 84.0, 86.0, 87.0, 84.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 50.0, 52.0, 50.0, 50.0, 50.0, 49.0, 49.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 63.0, 63.0, 58.0, 56.0, 57.0, 58.0, 57.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 80.0, 78.0, 81.0, 82.0, 80.0, 81.0, 77.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 134.0, 130.0, 134.0, 134.0, 137.0, 136.0, 186.0, 141.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 222.0, 184.0, 182.0, 185.0, 186.0, 182.0, 183.0, 181.0, 182.0], "value": 191.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 66.0, 67.0, 66.0, 66.0, 69.0, 67.0], "value": 67.1, "unit": "score"}]}]} 15:17:13 INFO - Return code: 0 15:17:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:17:13 INFO - # TBPL SUCCESS # 15:17:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:17:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:17:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:17:13 INFO - 2017-05-03 15:17:13.261 screenresolution[2533:36843] starting screenresolution argv=screenresolution get 15:17:13 INFO - 2017-05-03 15:17:13.264 screenresolution[2533:36843] Display 0: 1600x1200x32@60 15:17:13 INFO - 2017-05-03 15:17:13.270 screenresolution[2534:36846] starting screenresolution argv=screenresolution list 15:17:13 INFO - Available Modes on Display 0 15:17:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:17:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:17:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:17:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:17:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:17:13 INFO - Intel Iris: 15:17:13 INFO - Chipset Model: Intel Iris 15:17:13 INFO - Type: GPU 15:17:13 INFO - Bus: Built-In 15:17:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:17:13 INFO - Vendor: Intel (0x8086) 15:17:13 INFO - Device ID: 0x0a2e 15:17:13 INFO - Revision ID: 0x0009 15:17:13 INFO - Displays: 15:17:13 INFO - Display: 15:17:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:17:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:17:13 INFO - Display Serial Number: 1600x1200 60 15:17:13 INFO - Main Display: Yes 15:17:13 INFO - Mirror: Off 15:17:13 INFO - Online: Yes 15:17:13 INFO - Rotation: Supported 15:17:13 INFO - Return code: 0 15:17:13 INFO - Running post-action listener: _resource_record_post_action 15:17:13 INFO - [mozharness: 2017-05-03 22:17:13.503239Z] Finished run-tests step (success) 15:17:13 INFO - Running post-run listener: _resource_record_post_run 15:17:13 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 1135104; Write bytes: 506969600; Read time: 4; Write time: 1601 15:17:13 INFO - TinderboxPrint: CPU usage
53.5% 15:17:13 INFO - TinderboxPrint: I/O read bytes / time
1,135,104 / 4 15:17:13 INFO - TinderboxPrint: I/O write bytes / time
506,969,600 / 1,601 15:17:13 INFO - TinderboxPrint: CPU idle
2,231.6 (46.5%) 15:17:13 INFO - TinderboxPrint: CPU system
955.9 (19.9%) 15:17:13 INFO - TinderboxPrint: CPU user
1,610.1 (33.6%) 15:17:13 INFO - TinderboxPrint: Swap in / out
209,190,912 / 0 15:17:13 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209132032; Write bytes: 211260928; Read time: 16124; Write time: 479 15:17:13 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 213504; Write bytes: 294295552; Read time: 1; Write time: 1113 15:17:13 INFO - Running post-run listener: _upload_blobber_files 15:17:13 INFO - Blob upload gear active. 15:17:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:17:13 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 15:17:13 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:17:13 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:17:14 INFO - (blobuploader) - INFO - Open directory for files ... 15:17:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:17:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:17:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:17:15 INFO - (blobuploader) - INFO - Done attempting. 15:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:17:15 INFO - (blobuploader) - INFO - Done attempting. 15:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:17:17 INFO - (blobuploader) - INFO - Done attempting. 15:17:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:17:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:17:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:17:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:17:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:17:19 INFO - (blobuploader) - INFO - Done attempting. 15:17:19 INFO - (blobuploader) - INFO - Iteration through files over. 15:17:19 INFO - Return code: 0 15:17:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:17:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:17:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/151bed26e2597970cbebef83bb4a9a265b24791ac1dd688f3c615c11963f9d065b76b87a00335b765187c039ac9480566f9585a90289968d73edd66d94f236f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d510263efb268369a58631d6c131b24ba5e847a31dc947bf36a6b6451b72bdc61ba60781f652b78f0ab8e2e2a77ece73af892129220d08e9ba872807d8f018dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1654f541616d8034e90b2a41dd0fe8722afba8f83d59118bfe1861f2774cd9a5a8de7262b163bf15741b8c9ddb8de3ff0296cc123d98f5ea79b0e0adb51690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36bf2253099f95bbfaa2e91f520179f9b4ae9e29801709c6b83ad63d3544ea5a17c41c66548bd3ce67e7d80c40d43b7bbc8c483551b581e08921b93a13ade174"} 15:17:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:17:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:17:19 INFO - Contents: 15:17:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/151bed26e2597970cbebef83bb4a9a265b24791ac1dd688f3c615c11963f9d065b76b87a00335b765187c039ac9480566f9585a90289968d73edd66d94f236f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d510263efb268369a58631d6c131b24ba5e847a31dc947bf36a6b6451b72bdc61ba60781f652b78f0ab8e2e2a77ece73af892129220d08e9ba872807d8f018dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1654f541616d8034e90b2a41dd0fe8722afba8f83d59118bfe1861f2774cd9a5a8de7262b163bf15741b8c9ddb8de3ff0296cc123d98f5ea79b0e0adb51690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36bf2253099f95bbfaa2e91f520179f9b4ae9e29801709c6b83ad63d3544ea5a17c41c66548bd3ce67e7d80c40d43b7bbc8c483551b581e08921b93a13ade174"} 15:17:19 INFO - Running post-run listener: copy_logs_to_upload_dir 15:17:19 INFO - Copying logs to upload dir... 15:17:19 INFO - mkdir: /builds/slave/test/build/upload/logs 15:17:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.643864 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-03 15:17:19.846663) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 15:17:19.849882) ========= 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.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/151bed26e2597970cbebef83bb4a9a265b24791ac1dd688f3c615c11963f9d065b76b87a00335b765187c039ac9480566f9585a90289968d73edd66d94f236f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d510263efb268369a58631d6c131b24ba5e847a31dc947bf36a6b6451b72bdc61ba60781f652b78f0ab8e2e2a77ece73af892129220d08e9ba872807d8f018dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1654f541616d8034e90b2a41dd0fe8722afba8f83d59118bfe1861f2774cd9a5a8de7262b163bf15741b8c9ddb8de3ff0296cc123d98f5ea79b0e0adb51690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36bf2253099f95bbfaa2e91f520179f9b4ae9e29801709c6b83ad63d3544ea5a17c41c66548bd3ce67e7d80c40d43b7bbc8c483551b581e08921b93a13ade174"} build_url:https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'https://queue.taskcluster.net/v1/task/VgH-euZXTHGrhzwi3k7N9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/151bed26e2597970cbebef83bb4a9a265b24791ac1dd688f3c615c11963f9d065b76b87a00335b765187c039ac9480566f9585a90289968d73edd66d94f236f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d510263efb268369a58631d6c131b24ba5e847a31dc947bf36a6b6451b72bdc61ba60781f652b78f0ab8e2e2a77ece73af892129220d08e9ba872807d8f018dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d1654f541616d8034e90b2a41dd0fe8722afba8f83d59118bfe1861f2774cd9a5a8de7262b163bf15741b8c9ddb8de3ff0296cc123d98f5ea79b0e0adb51690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/36bf2253099f95bbfaa2e91f520179f9b4ae9e29801709c6b83ad63d3544ea5a17c41c66548bd3ce67e7d80c40d43b7bbc8c483551b581e08921b93a13ade174"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 15:17:19.885384) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:17:19.885764) ========= 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.Vk2G3TAALT/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.VGujt5R1Za/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.005234 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 15:17:19.935222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 15:17:19.935622) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 15:17:19.935929) ========= ========= Total master_lag: 0.19 =========