builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0085 starttime: 1490406379.66 results: success (0) buildid: 20170324171816 builduid: 9f18743f6fcf48938328caab675bd23f revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.657137) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.657668) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.657996) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.686339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.686856) ========= 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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-03-24 18:46:19-- 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% 21.4M=0.001s 2017-03-24 18:46:19 (21.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128730 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.844283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.845050) ========= 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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.026546 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:19.898716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 18:46:19.899597) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65b0ac174753b22c01156d72fb42d2abd3176dd1 --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 65b0ac174753b22c01156d72fb42d2abd3176dd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-03-24 18:46:19,982 truncating revision to first 12 chars 2017-03-24 18:46:19,982 Setting DEBUG logging. 2017-03-24 18:46:19,983 attempt 1/10 2017-03-24 18:46:19,983 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65b0ac174753?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-24 18:46:21,299 unpacking tar archive at: mozilla-central-65b0ac174753/testing/mozharness/ program finished with exit code 0 elapsedTime=1.759808 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-24 18:46:21.685827) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:21.686239) ========= script_repo_revision: 65b0ac174753b22c01156d72fb42d2abd3176dd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:21.686762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:21.687149) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-24 18:46:21.707163) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-24 18:46:21.707516) ========= /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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:46:21 INFO - MultiFileLogger online at 20170324 18:46:21 in /builds/slave/test 18:46:21 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 18:46:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:21 INFO - {'append_to_log': False, 18:46:21 INFO - 'base_work_dir': '/builds/slave/test', 18:46:21 INFO - 'blob_upload_branch': 'Firefox', 18:46:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:21 INFO - 'branch': 'Firefox', 18:46:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:21 INFO - 'config_files': ('talos/mac_config.py',), 18:46:21 INFO - 'default_actions': ('clobber', 18:46:21 INFO - 'read-buildbot-config', 18:46:21 INFO - 'download-and-extract', 18:46:21 INFO - 'populate-webroot', 18:46:21 INFO - 'create-virtualenv', 18:46:21 INFO - 'install', 18:46:21 INFO - 'run-tests'), 18:46:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:21 INFO - 'download_minidump_stackwalk': True, 18:46:21 INFO - 'download_symbols': 'ondemand', 18:46:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:21 INFO - 'gecko_profile': False, 18:46:21 INFO - 'gecko_profile_interval': 0, 18:46:21 INFO - 'installer_path': 'installer.exe', 18:46:21 INFO - 'log_level': 'info', 18:46:21 INFO - 'log_name': 'talos', 18:46:21 INFO - 'log_to_console': True, 18:46:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:46:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:46:21 INFO - 'opt_config_files': (), 18:46:21 INFO - 'pip_index': False, 18:46:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:21 INFO - 'cmd': ('bash', 18:46:21 INFO - '-c', 18:46:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:21 INFO - 'enabled': True, 18:46:21 INFO - 'halt_on_failure': False, 18:46:21 INFO - 'name': 'check_screen_resolution'},), 18:46:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:21 INFO - 'cmd': ('bash', 18:46:21 INFO - '-c', 18:46:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:21 INFO - 'enabled': True, 18:46:21 INFO - 'halt_on_failure': False, 18:46:21 INFO - 'name': 'check_screen_resolution'},), 18:46:21 INFO - 'run_cmd_checks_enabled': True, 18:46:21 INFO - 'suite': 'dromaeojs', 18:46:21 INFO - 'system_bits': '32', 18:46:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:21 INFO - 'title': 't-yosemite-r7-0085', 18:46:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:21 INFO - 'use_talos_json': True, 18:46:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:21 INFO - 'work_dir': 'build'} 18:46:21 INFO - [mozharness: 2017-03-25 01:46:21.903336Z] Running clobber step. 18:46:21 INFO - Running pre-action listener: _resource_record_pre_action 18:46:21 INFO - Running main action method: clobber 18:46:21 INFO - rmtree: /builds/slave/test/build 18:46:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:24 INFO - Running post-action listener: _resource_record_post_action 18:46:24 INFO - [mozharness: 2017-03-25 01:46:24.344939Z] Finished clobber step (success) 18:46:24 INFO - [mozharness: 2017-03-25 01:46:24.345108Z] Running read-buildbot-config step. 18:46:24 INFO - Running pre-action listener: _resource_record_pre_action 18:46:24 INFO - Running main action method: read_buildbot_config 18:46:24 INFO - Using buildbot properties: 18:46:24 INFO - { 18:46:24 INFO - "project": "", 18:46:24 INFO - "product": "firefox", 18:46:24 INFO - "script_repo_revision": "production", 18:46:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:46:24 INFO - "repository": "", 18:46:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:46:24 INFO - "buildid": "20170324171816", 18:46:24 INFO - "pgo_build": "False", 18:46:24 INFO - "basedir": "/builds/slave/test", 18:46:24 INFO - "buildnumber": 13, 18:46:24 INFO - "slavename": "t-yosemite-r7-0085", 18:46:24 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:46:24 INFO - "platform": "yosemite_r7", 18:46:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:46:24 INFO - "branch": "mozilla-central", 18:46:24 INFO - "repo_path": "mozilla-central", 18:46:24 INFO - "revision": "65b0ac174753b22c01156d72fb42d2abd3176dd1", 18:46:24 INFO - "stage_platform": "macosx64", 18:46:24 INFO - "builduid": "9f18743f6fcf48938328caab675bd23f", 18:46:24 INFO - "slavebuilddir": "test" 18:46:24 INFO - } 18:46:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:46:24 INFO - Running post-action listener: _resource_record_post_action 18:46:24 INFO - [mozharness: 2017-03-25 01:46:24.348569Z] Finished read-buildbot-config step (success) 18:46:24 INFO - [mozharness: 2017-03-25 01:46:24.348695Z] Running download-and-extract step. 18:46:24 INFO - Running pre-action listener: _resource_record_pre_action 18:46:24 INFO - Running main action method: download_and_extract 18:46:24 INFO - mkdir: /builds/slave/test/build/tests 18:46:24 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')]} 18:46:24 INFO - trying https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:46:24 INFO - Downloading https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/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 18:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/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 18:46:25 INFO - Downloaded 1325 bytes. 18:46:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:46:25 INFO - Using the following test package requirements: 18:46:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:46:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:46:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:46:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:46:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'jsshell-mac.zip'], 18:46:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:46:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:46:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:46:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:46:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:46:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:46:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:46:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:46:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:46:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:46:25 INFO - Fetch https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:46:26 INFO - Expected file size: 52411785 18:46:26 INFO - Obtained file size: 52411785 18:46:43 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 18:46:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:46:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:46:43 INFO - Fetch https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:46:45 INFO - Expected file size: 52411785 18:46:45 INFO - Obtained file size: 52411785 18:47:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:47:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:47:00 INFO - Fetch https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:47:01 INFO - Expected file size: 14404171 18:47:01 INFO - Obtained file size: 14404171 18:47:02 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')]} 18:47:02 INFO - trying https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:47:02 INFO - Downloading https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/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 18:47:04 INFO - Downloaded 75592296 bytes. 18:47:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:47:04 INFO - mkdir: /builds/slave/test/properties 18:47:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:04 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:04 INFO - Contents: 18:47:04 INFO - build_url:https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:47:05 INFO - Running post-action listener: _resource_record_post_action 18:47:05 INFO - Running post-action listener: set_extra_try_arguments 18:47:05 INFO - [mozharness: 2017-03-25 01:47:05.875518Z] Finished download-and-extract step (success) 18:47:05 INFO - [mozharness: 2017-03-25 01:47:05.875672Z] Running populate-webroot step. 18:47:05 INFO - Running pre-action listener: _resource_record_pre_action 18:47:05 INFO - Running main action method: populate_webroot 18:47:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:47:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:47:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:47:05 INFO - '--tppagecycles', 18:47:05 INFO - '7'], 18:47:05 INFO - 'tests': ['tsvgm']}}, 18:47:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['damp', 'tps']}, 18:47:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'tests': ['damp', 'tps']}, 18:47:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['dromaeo_dom']}, 18:47:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['basic_compositor_video']}, 18:47:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:47:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['a11yr', 18:47:05 INFO - 'ts_paint', 18:47:05 INFO - 'tpaint', 18:47:05 INFO - 'sessionrestore', 18:47:05 INFO - 'sessionrestore_no_auto_restore', 18:47:05 INFO - 'tabpaint']}, 18:47:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['tsvgx', 18:47:05 INFO - 'tsvgr_opacity', 18:47:05 INFO - 'tart', 18:47:05 INFO - 'tscrollx', 18:47:05 INFO - 'cart', 18:47:05 INFO - 'tsvg_static']}, 18:47:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:05 INFO - 'tsvgr_opacity', 18:47:05 INFO - 'tart', 18:47:05 INFO - 'tscrollx', 18:47:05 INFO - 'cart', 18:47:05 INFO - 'tsvg_static']}, 18:47:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'talos_options': ['--disable-e10s'], 18:47:05 INFO - 'tests': ['tp5o']}, 18:47:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:47:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'talos_options': ['--disable-e10s', 18:47:05 INFO - '--xperf_path', 18:47:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:05 INFO - 'tests': ['tp5n']}, 18:47:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:47:05 INFO - 'talos_options': ['--xperf_path', 18:47:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:05 INFO - 'tests': ['tp5n']}}, 18:47:05 INFO - 'talos.zip': {'path': '', 18:47:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:47:05 INFO - Running post-action listener: _resource_record_post_action 18:47:05 INFO - [mozharness: 2017-03-25 01:47:05.894285Z] Finished populate-webroot step (success) 18:47:05 INFO - [mozharness: 2017-03-25 01:47:05.894428Z] Running create-virtualenv step. 18:47:05 INFO - Running pre-action listener: _resource_record_pre_action 18:47:05 INFO - Running main action method: create_virtualenv 18:47:05 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:05 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 18:47:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:06 INFO - Using real prefix '/tools/python27' 18:47:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:06 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:09 INFO - Installing pip.................done. 18:47:09 INFO - Return code: 0 18:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:47:09 INFO - Reading from file tmpfile_stdout 18:47:09 INFO - Output received: 18:47:09 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) 18:47:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:09 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:09 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 18:47:09 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 18:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:09 INFO - 'HOME': '/Users/cltbld', 18:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:09 INFO - 'LOGNAME': 'cltbld', 18:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:09 INFO - 'MOZ_NO_REMOTE': '1', 18:47:09 INFO - 'NO_EM_RESTART': '1', 18:47:09 INFO - 'PAGER': '/bin/cat', 18:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:09 INFO - 'PWD': '/builds/slave/test', 18:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:09 INFO - 'SHELL': '/bin/bash', 18:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:09 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:09 INFO - 'USER': 'cltbld', 18:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:09 INFO - 'XPC_FLAGS': '0x0', 18:47:09 INFO - 'XPC_SERVICE_NAME': '0', 18:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:10 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 18:47:10 INFO - Cleaning up... 18:47:10 INFO - Return code: 0 18:47:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:47:10 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')]} 18:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:10 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')]} 18:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:10 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:10 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 18:47:10 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 18:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:10 INFO - 'HOME': '/Users/cltbld', 18:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:10 INFO - 'LOGNAME': 'cltbld', 18:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:10 INFO - 'MOZ_NO_REMOTE': '1', 18:47:10 INFO - 'NO_EM_RESTART': '1', 18:47:10 INFO - 'PAGER': '/bin/cat', 18:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:10 INFO - 'PWD': '/builds/slave/test', 18:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:10 INFO - 'SHELL': '/bin/bash', 18:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:10 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:10 INFO - 'USER': 'cltbld', 18:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:10 INFO - 'XPC_FLAGS': '0x0', 18:47:10 INFO - 'XPC_SERVICE_NAME': '0', 18:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:10 INFO - Downloading/unpacking psutil>=3.1.1 18:47: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 18:47: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 18:47: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 18:47: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 18:47: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 18:47: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 18:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:14 INFO - Installing collected packages: psutil 18:47:14 INFO - Running setup.py install for psutil 18:47:14 INFO - building 'psutil._psutil_osx' extension 18:47:14 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 18:47:14 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 18:47:14 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 18:47:14 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 18:47:14 INFO - building 'psutil._psutil_posix' extension 18:47:14 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 18:47:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:47:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:47:15 INFO - ^ 18:47:15 INFO - 1 warning generated. 18:47:15 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 18:47:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:15 INFO - Successfully installed psutil 18:47:15 INFO - Cleaning up... 18:47:15 INFO - Return code: 0 18:47:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:47:15 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')]} 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:15 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')]} 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:16 INFO - 'HOME': '/Users/cltbld', 18:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:16 INFO - 'LOGNAME': 'cltbld', 18:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:16 INFO - 'MOZ_NO_REMOTE': '1', 18:47:16 INFO - 'NO_EM_RESTART': '1', 18:47:16 INFO - 'PAGER': '/bin/cat', 18:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:16 INFO - 'PWD': '/builds/slave/test', 18:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:16 INFO - 'SHELL': '/bin/bash', 18:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:16 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:16 INFO - 'USER': 'cltbld', 18:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:16 INFO - 'XPC_FLAGS': '0x0', 18:47:16 INFO - 'XPC_SERVICE_NAME': '0', 18:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:47:19 INFO - Installing collected packages: mozsystemmonitor 18:47:19 INFO - Running setup.py install for mozsystemmonitor 18:47:19 INFO - Successfully installed mozsystemmonitor 18:47:19 INFO - Cleaning up... 18:47:19 INFO - Return code: 0 18:47:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:47:19 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')]} 18:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:20 INFO - 'HOME': '/Users/cltbld', 18:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PAGER': '/bin/cat', 18:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:20 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - 'XPC_FLAGS': '0x0', 18:47:20 INFO - 'XPC_SERVICE_NAME': '0', 18:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:20 INFO - Downloading/unpacking jsonschema==2.5.1 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:47:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:23 INFO - Downloading functools32-3.2.3-2.tar.gz 18:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:47:23 INFO - warning: no files found matching '*.txt' 18:47:23 INFO - no previously-included directories found matching 'build' 18:47:23 INFO - no previously-included directories found matching 'dist' 18:47:23 INFO - no previously-included directories found matching '.git*' 18:47:23 INFO - Installing collected packages: jsonschema, functools32 18:47:23 INFO - Running setup.py install for functools32 18:47:23 INFO - warning: no files found matching '*.txt' 18:47:23 INFO - no previously-included directories found matching 'build' 18:47:23 INFO - no previously-included directories found matching 'dist' 18:47:23 INFO - no previously-included directories found matching '.git*' 18:47:23 INFO - Successfully installed jsonschema functools32 18:47:23 INFO - Cleaning up... 18:47:23 INFO - Return code: 0 18:47:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:23 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:23 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 18:47:23 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 18:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:23 INFO - 'HOME': '/Users/cltbld', 18:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:23 INFO - 'LOGNAME': 'cltbld', 18:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:23 INFO - 'MOZ_NO_REMOTE': '1', 18:47:23 INFO - 'NO_EM_RESTART': '1', 18:47:23 INFO - 'PAGER': '/bin/cat', 18:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:23 INFO - 'PWD': '/builds/slave/test', 18:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:23 INFO - 'SHELL': '/bin/bash', 18:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:23 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:23 INFO - 'USER': 'cltbld', 18:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:23 INFO - 'XPC_FLAGS': '0x0', 18:47:23 INFO - 'XPC_SERVICE_NAME': '0', 18:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:47:24 INFO - Cleaning up... 18:47:24 INFO - Return code: 0 18:47:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:47:24 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')]} 18:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:24 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')]} 18:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:24 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:24 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 18:47:24 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 18:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:24 INFO - 'HOME': '/Users/cltbld', 18:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:24 INFO - 'LOGNAME': 'cltbld', 18:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:24 INFO - 'MOZ_NO_REMOTE': '1', 18:47:24 INFO - 'NO_EM_RESTART': '1', 18:47:24 INFO - 'PAGER': '/bin/cat', 18:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:24 INFO - 'PWD': '/builds/slave/test', 18:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:24 INFO - 'SHELL': '/bin/bash', 18:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:24 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:24 INFO - 'USER': 'cltbld', 18:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:24 INFO - 'XPC_FLAGS': '0x0', 18:47:24 INFO - 'XPC_SERVICE_NAME': '0', 18:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:24 INFO - Downloading/unpacking blobuploader==1.2.4 18:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:26 INFO - Downloading blobuploader-1.2.4.tar.gz 18:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:47:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:47:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:27 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 18:47:28 INFO - Downloading docopt-0.6.1.tar.gz 18:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:47:28 INFO - Installing collected packages: blobuploader, requests, docopt 18:47:28 INFO - Running setup.py install for blobuploader 18:47:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:28 INFO - Running setup.py install for requests 18:47:29 INFO - Running setup.py install for docopt 18:47:29 INFO - Successfully installed blobuploader requests docopt 18:47:29 INFO - Cleaning up... 18:47:29 INFO - Return code: 0 18:47:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:29 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')]} 18:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:29 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')]} 18:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:29 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:29 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 18:47:29 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 18:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:29 INFO - 'HOME': '/Users/cltbld', 18:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:29 INFO - 'LOGNAME': 'cltbld', 18:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:29 INFO - 'MOZ_NO_REMOTE': '1', 18:47:29 INFO - 'NO_EM_RESTART': '1', 18:47:29 INFO - 'PAGER': '/bin/cat', 18:47:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:29 INFO - 'PWD': '/builds/slave/test', 18:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:29 INFO - 'SHELL': '/bin/bash', 18:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:29 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:29 INFO - 'USER': 'cltbld', 18:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:29 INFO - 'XPC_FLAGS': '0x0', 18:47:29 INFO - 'XPC_SERVICE_NAME': '0', 18:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:29 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-C0YV8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:29 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-dkDDa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:29 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-WyR_pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:29 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-YlNDQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-VnmpMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-UGQgNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ZB1DK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-cYS6Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-qnrql4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-00C3Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-KAD64k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-7WGmpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-cANjcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:30 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-nIK5sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-92GtGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-qMVtUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-xCNr9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:31 INFO - Running setup.py install for manifestparser 18:47:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:31 INFO - Running setup.py install for mozcrash 18:47:31 INFO - Running setup.py install for mozdebug 18:47:31 INFO - Running setup.py install for mozdevice 18:47:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:31 INFO - Running setup.py install for mozfile 18:47:32 INFO - Running setup.py install for mozhttpd 18:47:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Running setup.py install for mozinfo 18:47:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Running setup.py install for mozInstall 18:47:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Running setup.py install for mozleak 18:47:32 INFO - Running setup.py install for mozlog 18:47:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:32 INFO - Running setup.py install for moznetwork 18:47:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Running setup.py install for mozprocess 18:47:33 INFO - Running setup.py install for mozprofile 18:47:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Running setup.py install for mozrunner 18:47:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Running setup.py install for mozscreenshot 18:47:33 INFO - Running setup.py install for moztest 18:47:33 INFO - Running setup.py install for mozversion 18:47:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:33 INFO - Cleaning up... 18:47:33 INFO - Return code: 0 18:47:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:33 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:33 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 18:47:33 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 18:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:33 INFO - 'HOME': '/Users/cltbld', 18:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:33 INFO - 'LOGNAME': 'cltbld', 18:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:33 INFO - 'MOZ_NO_REMOTE': '1', 18:47:33 INFO - 'NO_EM_RESTART': '1', 18:47:33 INFO - 'PAGER': '/bin/cat', 18:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:33 INFO - 'PWD': '/builds/slave/test', 18:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:33 INFO - 'SHELL': '/bin/bash', 18:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:33 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:33 INFO - 'USER': 'cltbld', 18:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:33 INFO - 'XPC_FLAGS': '0x0', 18:47:33 INFO - 'XPC_SERVICE_NAME': '0', 18:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-hm6_gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-px8xb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Bs1ahs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Cy7hbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-DaVeKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-a0gcpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-knhJia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:34 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)) 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:34 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-cH5byN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-EiKSn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-EV5m2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-FXfTKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-rpBD2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-jXzC2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zLhPpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-FNqd5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-44qiVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:35 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)) 18:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:35 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-V9Acsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:36 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)) 18:47:36 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)) 18:47:36 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)) 18:47:36 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)) 18:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:47:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:36 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 18:47:38 INFO - Downloading blessings-1.6.tar.gz 18:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:38 INFO - Installing collected packages: blessings 18:47:38 INFO - Running setup.py install for blessings 18:47:38 INFO - Successfully installed blessings 18:47:38 INFO - Cleaning up... 18:47:39 INFO - Return code: 0 18:47:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:39 INFO - Reading from file tmpfile_stdout 18:47:39 INFO - Current package versions: 18:47:39 INFO - blessings == 1.6 18:47:39 INFO - blobuploader == 1.2.4 18:47:39 INFO - docopt == 0.6.1 18:47:39 INFO - functools32 == 3.2.3-2 18:47:39 INFO - jsonschema == 2.5.1 18:47:39 INFO - manifestparser == 1.1 18:47:39 INFO - mozInstall == 1.12 18:47:39 INFO - mozcrash == 1.0 18:47:39 INFO - mozdebug == 0.1 18:47:39 INFO - mozdevice == 0.49 18:47:39 INFO - mozfile == 1.2 18:47:39 INFO - mozhttpd == 0.7 18:47:39 INFO - mozinfo == 0.9 18:47:39 INFO - mozleak == 0.1 18:47:39 INFO - mozlog == 3.4 18:47:39 INFO - moznetwork == 0.27 18:47:39 INFO - mozprocess == 0.25 18:47:39 INFO - mozprofile == 0.28 18:47:39 INFO - mozrunner == 6.13 18:47:39 INFO - mozscreenshot == 0.1 18:47:39 INFO - mozsystemmonitor == 0.3 18:47:39 INFO - moztest == 0.8 18:47:39 INFO - mozversion == 1.4 18:47:39 INFO - psutil == 3.1.1 18:47:39 INFO - requests == 1.2.3 18:47:39 INFO - wsgiref == 0.1.2 18:47:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:39 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 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:39 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 18:47:39 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 18:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:39 INFO - 'HOME': '/Users/cltbld', 18:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:39 INFO - 'LOGNAME': 'cltbld', 18:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:39 INFO - 'MOZ_NO_REMOTE': '1', 18:47:39 INFO - 'NO_EM_RESTART': '1', 18:47:39 INFO - 'PAGER': '/bin/cat', 18:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:39 INFO - 'PWD': '/builds/slave/test', 18:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:39 INFO - 'SHELL': '/bin/bash', 18:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:39 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:39 INFO - 'USER': 'cltbld', 18:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:39 INFO - 'XPC_FLAGS': '0x0', 18:47:39 INFO - 'XPC_SERVICE_NAME': '0', 18:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 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)) 18:47:39 INFO - Cleaning up... 18:47:39 INFO - Return code: 0 18:47:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107713b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107716768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe148d95000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, '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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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 18:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:39 INFO - 'HOME': '/Users/cltbld', 18:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:39 INFO - 'LOGNAME': 'cltbld', 18:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:39 INFO - 'MOZ_NO_REMOTE': '1', 18:47:39 INFO - 'NO_EM_RESTART': '1', 18:47:39 INFO - 'PAGER': '/bin/cat', 18:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:39 INFO - 'PWD': '/builds/slave/test', 18:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:39 INFO - 'SHELL': '/bin/bash', 18:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:47:39 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:39 INFO - 'USER': 'cltbld', 18:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:39 INFO - 'XPC_FLAGS': '0x0', 18:47:39 INFO - 'XPC_SERVICE_NAME': '0', 18:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:47:39 INFO - Cleaning up... 18:47:39 INFO - Return code: 0 18:47:39 INFO - Running post-action listener: _resource_record_post_action 18:47:39 INFO - Running post-action listener: _start_resource_monitoring 18:47:39 INFO - Starting resource monitoring. 18:47:39 INFO - [mozharness: 2017-03-25 01:47:39.859204Z] Finished create-virtualenv step (success) 18:47:39 INFO - [mozharness: 2017-03-25 01:47:39.860236Z] Running install step. 18:47:39 INFO - Running pre-action listener: _resource_record_pre_action 18:47:39 INFO - Running main action method: install 18:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:40 INFO - Reading from file tmpfile_stdout 18:47:40 INFO - Detecting whether we're running mozinstall >=1.0... 18:47:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:47:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:47:40 INFO - Reading from file tmpfile_stdout 18:47:40 INFO - Output received: 18:47:40 INFO - Usage: mozinstall [options] installer 18:47:40 INFO - Options: 18:47:40 INFO - -h, --help show this help message and exit 18:47:40 INFO - -d DEST, --destination=DEST 18:47:40 INFO - Directory to install application into. [default: 18:47:40 INFO - "/builds/slave/test"] 18:47:40 INFO - --app=APP Application being installed. [default: firefox] 18:47:40 INFO - mkdir: /builds/slave/test/build/application 18:47:40 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'] 18:47:40 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 18:48:00 INFO - Reading from file tmpfile_stdout 18:48:00 INFO - Output received: 18:48:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:48:00 INFO - Running post-action listener: _resource_record_post_action 18:48:00 INFO - [mozharness: 2017-03-25 01:48:00.524101Z] Finished install step (success) 18:48:00 INFO - [mozharness: 2017-03-25 01:48:00.524277Z] Running run-tests step. 18:48:00 INFO - Running pre-action listener: _resource_record_pre_action 18:48:00 INFO - Running main action method: run_tests 18:48:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:00 INFO - 2017-03-24 18:48:00.538 screenresolution[1623:7513] starting screenresolution argv=screenresolution get 18:48:00 INFO - 2017-03-24 18:48:00.541 screenresolution[1623:7513] Display 0: 1600x1200x32@60 18:48:00 INFO - 2017-03-24 18:48:00.547 screenresolution[1624:7516] starting screenresolution argv=screenresolution list 18:48:00 INFO - Available Modes on Display 0 18:48:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:00 INFO - Intel Iris: 18:48:00 INFO - Chipset Model: Intel Iris 18:48:00 INFO - Type: GPU 18:48:00 INFO - Bus: Built-In 18:48:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:00 INFO - Vendor: Intel (0x8086) 18:48:00 INFO - Device ID: 0x0a2e 18:48:00 INFO - Revision ID: 0x0009 18:48:00 INFO - Displays: 18:48:00 INFO - Display: 18:48:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:00 INFO - Display Serial Number: 1600x1200 60 18:48:00 INFO - Main Display: Yes 18:48:00 INFO - Mirror: Off 18:48:00 INFO - Online: Yes 18:48:00 INFO - Rotation: Supported 18:48:00 INFO - Return code: 0 18:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:00 INFO - Python 2.7.3 18:48:00 INFO - Return code: 0 18:48:00 INFO - grabbing minidump binary from tooltool 18:48:00 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')]} 18:48:00 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 0x1074f1d30>, '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 0x107418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074e6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:00 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 18:48:00 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 18:48:00 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 18:48:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:00 INFO - Return code: 0 18:48:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:48:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:00 INFO - ENV: RUST_BACKTRACE is now 1 18:48:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:48:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:00 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/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--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 18:48:00 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/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --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 18:48:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lrM5EzAJDD/Render', 18:48:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:00 INFO - 'HOME': '/Users/cltbld', 18:48:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:00 INFO - 'LOGNAME': 'cltbld', 18:48:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:00 INFO - 'MOZ_NO_REMOTE': '1', 18:48:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:00 INFO - 'NO_EM_RESTART': '1', 18:48:00 INFO - 'PAGER': '/bin/cat', 18:48:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:00 INFO - 'PWD': '/builds/slave/test', 18:48:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:00 INFO - 'RUST_BACKTRACE': '1', 18:48:00 INFO - 'SHELL': '/bin/bash', 18:48:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AVIwtaeeJZ/Listeners', 18:48:00 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 18:48:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:00 INFO - 'USER': 'cltbld', 18:48:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:00 INFO - 'XPC_FLAGS': '0x0', 18:48:00 INFO - 'XPC_SERVICE_NAME': '0', 18:48:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:00 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/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--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 18:48:01 INFO - mozversion application_buildid: 20170324171816 18:48:01 INFO - mozversion application_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 18:48:01 INFO - mozversion application_display_name: Nightly 18:48:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:01 INFO - mozversion application_name: Firefox 18:48:01 INFO - mozversion application_remotingname: firefox 18:48:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:48:01 INFO - mozversion application_vendor: Mozilla 18:48:01 INFO - mozversion application_version: 55.0a1 18:48:01 INFO - mozversion platform_buildid: 20170324171816 18:48:01 INFO - mozversion platform_changeset: 65b0ac174753b22c01156d72fb42d2abd3176dd1 18:48:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:48:01 INFO - mozversion platform_version: 55.0a1 18:48:01 INFO - using testdate: 1490406481 18:48:01 INFO - actual date: 1490406481 18:48:01 INFO - starting webserver on 'localhost:49242' 18:48:01 INFO - SUITE-START | Running 2 tests 18:48:01 INFO - TEST-START | dromaeo_css 18:48:01 INFO - operating with platform_type : mac_ 18:48:01 INFO - Initialising browser for dromaeo_css test... 18:48:01 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpZ9h6X6/profile http://localhost:49242/getInfo.html) 18:48:03 INFO - PID 1634 | __metrics Screen width/height:1600/1200 18:48:03 INFO - PID 1634 | colorDepth:24 18:48:03 INFO - PID 1634 | Browser inner width/height: 1024/689 18:48:03 INFO - PID 1634 | __metrics 18:48:03 INFO - PID 1634 | 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" 18:48:03 INFO - PID 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:48:04 INFO - TEST-INFO | 1634: exit 0 18:48:04 INFO - Browser initialized. 18:48:04 INFO - Running cycle 1/1 for dromaeo_css test... 18:48:04 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpZ9h6X6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:16 INFO - PID 1636 | RSS: Main: 233885696 18:48:16 INFO - PID 1636 | 18:51:21 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 18:51:23 INFO - PID 1636 | RSS: Main: 384045056 18:51:23 INFO - PID 1636 | 18:53:06 INFO - PID 1636 | ************************* 18:53:06 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 18:53:06 INFO - PID 1636 | 18:53:06 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:53:06 INFO - PID 1636 | Full stack: 18:53:06 INFO - PID 1636 | ************************* 18:54:26 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 18:54:27 INFO - PID 1636 | RSS: Main: 370982912 18:54:27 INFO - PID 1636 | 18:57:29 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 18:57:30 INFO - PID 1636 | RSS: Main: 329277440 18:57:30 INFO - PID 1636 | 19:00:32 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 19:00:33 INFO - PID 1636 | RSS: Main: 361443328 19:00:33 INFO - PID 1636 | 19:03:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 19:03:37 INFO - PID 1636 | RSS: Main: 335294464 19:03:37 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 19:06:41 INFO - PID 1636 | RSS: Main: 584400896 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | __start_tp_report 19:06:41 INFO - PID 1636 | _x_x_mozilla_page_load 19:06:41 INFO - PID 1636 | _x_x_mozilla_page_load_details 19:06:41 INFO - PID 1636 | |i|pagename|runs| 19:06:41 INFO - PID 1636 | |0;dojo.html;3167.144870884861;3448.8275586220684;3471.975767148689;3514.4376899696044;3530.24099818539;6051.062085376768;6075.787347442841;6108.114323423112;6121.0262130507535;6123.926141497245;6042.7891611279465;6104.114903339024;6108.129596501696;6112.510999120071;6142.406692085125;5052.595792336604;5374.059539580578;5477.808276710316;5482.150266708666;5622.606417550773;5550.306211723535;5555.777768889239;5581.190727344532;5600.18797274395;5603.2435621191225;5326.147816349366;5383.192521121824;5392.164214546712;5392.514673679388;5395.271638328814;5478.780848766045;5492.945070549284;5498.615096943216;5579.46995035471;5596.188552659883;4460.799264033127;5432.049391356536;5439.075357189287;5504.504594586467;5542.695151766651;5960.552958528085;5970.104484327342;6021.879562408727;6161.9691901540655;6192.102145188967;6199.535034872358;6267.937320626781;6271.341509141565;6275.09011193379;6281.7173227204885;5706.71466426677;6367.395097465734;6441.130447389589;6517.4134327910715;6523.4455071319;6158.5381096417495;6182.721777520022;6223.564350495421;6224.813255602339;6231;6428.742850286029;6670.933290667128;6678.031685405589;6694.09629699994;6694.732210711517;5950.9107363389585;6202.317745048041;6222.346653601307;6234.688265586702;6239.688015599202;5868.823935281948;5948.910766338508;6343.302236753953;6352.809415717536;6355.745770169141;6266.279377871603;6268.905966410507;6332.8416789579705;6408.967955160194;6409;6421.678916054178;6422.967885160544;6460;6467.838304042437;6477.4494167996245;5238.607104467104;5240.9213861792105;5242;5251.0548101341365;5287.68273903567;3978.403239514038;4499.932501012488;4589.472210695746;4638.79125439356;4649.744264065509;6092.695365231721;6094.177286066413;6094.939050609437;6101.84745381369;6108.664023478751;6173.753049877954;6314.684265786692;6326.367363263729;6337.714802833883;6352.523560732871;6086;6102.216595924226;6109.20580324555;6110.938890611126;6141.877162456726;5690.573207009491;5699.230603868507;5708.20085188074;5735.1684005818925;5760.279965004374;6584.473242140713;6590.80227593173;6611.768588099393;6614.8346291343105;6616.603003819786;6457.354264573505;6472.708728107341;6473.255575608677;6480.935190648033;6488.351164883663;6413.005984072476;6483.578567393104;6508.60948343101;6551.967240163769;6602.933970660232;6344.333844946214;6357.3006969234275;6357.904631430625;6361.4274715274;6365.809025729235;6158.507319414526;6242.250929888442;6396.360363963567;6441.71012304438;6463.192100987377;6304.338044505261;6321.11504389395;6327.082573026977;6327.493800496041;6337.7781777637565;6309.779157729457;6317.368263173646;6317.9052314214405;6318.873622527615;6326.810195694136;5619.7917447747295;6125.84596676928;6201.534884883562;6213.00591905293;6233.96883015582;5870.9412905870395;6308.589941653778;6312.43188113072;6312.810615681353;6315.147455093687;6274.1843560336865;6280.371962803683;6282.780102696383;6287.30839607652;6294.842628934313 19:06:41 INFO - PID 1636 | |1;ext.html;706.2266817834471;721.7365661533539;753.6156560154319;1145.106816682987;1164.8019836627773;27606.75769590368;28041.43917121659;28050.719492805067;28080;28850;29063;29264.56103158449;37306.25387492255;37772;37918.62081379185;8493.617787199591;8660.220580147785;8663.133686631349;8664.26353759928;8664.566771661423;6095.08573713942;8480.66077356905;8618.827623447527;8629.180227878342;8641.438306510088;6273.309935907047;7751.18612545683;8345.874811877828;8442.493450392996;8643.222110010129;6891.965540172318;8632.65469381224;8728.083551227157;8742.825143497159;8801.559922003938;5137.666051706665;8394.664213431455;8504.107068757816;8524.531150786766;8548.70079547219;27126.32184195391;27425.451490970267;27446.862765686044;27447;27464.588031179548;2946.1161651504462;2948.9852550737323;2968.3766409054124;2971.4651362754694;2988.5068963621193;27815.887364505397;27966.300842478897;28148.85925570359;28338.716612833814;28738.281542961384;2780.4856101621267;2792.581112833071;2821.534446816273;2823.7035111313426;2827.6465441819773;348064.51935480576;349490.27293771843;352231;352459;353677;52022;52149.47850521522;52182;52210;52337.73831130896;54531.454685452634;54535.90928181414;54577;54608;54660.453395465534;14254.216018118896;14675.972681912377;14720.19038952868;14737.852621473863;14770.187639679705;11883.64349069512;11891.643250702493;11894.92945634897;11901.464434100486;11913.463894124785;10620.522076506575;10794.784104318027;12566.05754568411;12796.232226066466;12827.935860320826;5343.545798607084;5569.888602227933;5580.944190558123;5599.188117722907;5604.355499117572;953.2612225525135;967.1634036558327;974.6783561424713;982.8820541535061;983.5426526664992;23937.940145216784;24151.275461736175;24171.879140604186;24206.15278465245;24341.756582433947;1071.3518321415588;1076.8115579773507;1117.1621284036971;1124.9775004499863;1127.1039523578775;12134.453949572291;12258.44836982338;12258.693532661577;12302.323372214614;12523.624291271277;450498;451327;452339;452851;452952;55886;56013;56078.719606401704;56131.43868561262;56183.43816561864;1447.6815100677834;1475.2771142140489;1526.419960415013;1532.869706074974;1541.267897748583;22205.555888882456;23580.764192357856;23582.410439739142;23614.29157125289;23638.29085127449;38780.6121938777;38894.2221155581;38903.80548097241;38910.61089389183;38914.416283756334;437529;437962;439049;439388;439623;31877;32802.671973280914;32910.506342404406;32927.50608740823;33897.49153762646;41444;41503.96240093901;41899.58100418957;41901.371479428446;41926.1614767709;24167.758322417256;27336.72663273342;29060.101188492376;29246.26884327909;29582.408351833274;29378.265543360732;29444.705552944197;29473.852630737565;30620;30707.539386909662;35592.46611300781;36777.63222367742;36825.81587092047;36929.44605830968;36970;28726;29935.611589914024;30356;30462.543061854532;30695;19822.07519334773;22011.559768804214;22062.66905996379;23822.404439889142;24393.26820195397 19:06:41 INFO - PID 1636 | |2;jquery.html;388.7413386352508;429.137433758146;439.417771452825;441.6466826538769;461.68374663355604;18125.728114078298;21021;21903.561928761414;22828.85855707214;23050.884745576248;19217.807821921775;22860.65709014366;23327;23473.82630868454;23751.287461376185;4629.3518907353;6048.213732214817;6179.938200617981;6282.24613046435;6286.842828929291;3641.2353405784816;3869.7678139311593;3878.3019056569806;5909.645421274716;6208.534359923001;6177.258728952532;6187.845303867394;6189.2572891252885;6202.4417802397775;6208.751649933997;5876.294844618652;6080.665563394034;6099.329073801879;6151.200343955257;6152.077188421727;5300.337457817773;5460.126379779256;5463.562914966793;5472.057419616059;5478.890422191574;23039.654405183766;27707.584386234223;28185.57721634177;28412.00557980461;28737.71262287392;2030.187924830065;2048.3752455501067;2064.153696984229;2075.937721868346;2141.6787481877686;15610.45363412275;16271.674566508604;16407.917960410123;16785.328586856635;16792;1633.9428120015862;1713.6658351621438;1715.802821366298;1745.1099939031058;1810.97283540747;158073;164644;166101;166565.16717416456;169940.15029924895;10145.746356341151;12274.32491212974;12407.25556466615;12427.875721242852;12523.874761252271;16634.833651663328;16636.6672666546;16646.50060498187;16665.16674166287;16675.91662041682;8649.264812491008;8850.468971861708;8893.510856902933;8960.731178064669;9047.185753282236;8402.495850249004;8424.578771061544;8607.397482176188;8623.870641940375;8642.135786421433;6439.839004024937;7541.886871696929;7836.529808211526;7854.253845884632;7876.818477228461;1976.8418526517844;2007.7590689117108;2095.088636443152;2095.8952052397317;2286.6112760830697;818.9754307370788;934.4673536084366;937.8991060025494;959.6881013670585;983.1397527163732;20152.29466968649;20831;20855.852928088796;20884.895575522027;20908.37274881756;914.2731528434911;922.4188761080476;953.7901661634429;974.8635191073255;999.8300288950895;10031.648892288735;10764.03123718869;10801.837972430263;10804.974607110022;11012.559497620005;567313;625920;719290;724620;730929;141607.29196353952;154726.45273547346;157941.2102939478;162469;164108;1191.2495128069434;1210.4794938176492;1249.5439164704999;1250.6060590914014;1255.656447601058;20380;21645.13419463204;21753;21860.45348866291;21943.890280548494;60161.39838601558;60290.69854650874;60803;61627.767444651756;61881.69059154675;83798;85416.29167416741;86578;87225.56387218024;88088.5595572018;7793.766187014398;8390;8467.280281176169;8776.990646075528;8848.778780530538;8979.910200897908;11702.648920532398;12894.226346419246;13153.93423032917;13203;10925.83611245816;10988.725281868017;11346.829797552879;11349.829752553553;11445.828312575484;9235.861462077939;9356.4386136832;10815.405152716676;10891.965263299973;10997.010269075821;11534.94232528832;11687.76624467489;11751.412429378326;11924.284542927453;11981.520739170335;6487.578307410004;8381.078081411162;8555.786105347415;8633.525156116222;8638.78403039929;11103.50034248477;11375.374354410587;11682.24065435744;11801.586944456903;11886.702832429259 19:06:41 INFO - PID 1636 | |3;mootools.html;1367.3573420492369;1432.133559196686;1448.6885319656265;1448.7030158817442;1460.6859525202083;9116.908830911689;10914.727131821688;10922;10930.344179349244;10933.726656833573;10606.469676516163;10721.98141176589;10902.454877256145;11038.454616353716;11042.282251653636;2477.9876100619517;2510.5606518859217;2522.016413598698;2523.570992931206;2527.5576774064557;2361.6929799126087;2365.787079162875;2367.6093444581625;2381.0832829360734;2442.4992876460374;2402.3994001499627;2513.447041650834;2516.6979962404444;2517.5216708825355;2517.5216708825355;2450.546648869947;2463.6304554316816;2468.4322605800676;2496.2885577610386;2502.1742824867933;2379.226751305815;2450.799108436853;2470.9135180268786;2484.7018357797;2488.2908371114327;11063.446827658585;11158.721031974184;11172.553097876156;11177.49701263445;11185;6258;6505.479561635105;6509.674516274167;6522.804315870531;6525.086487891699;3965.1474932889278;4034.172994536113;4084.0810817566107;4132.669386449077;4136.58634136587;1166.650004998497;1172.6540670502222;1210.939453027345;1226.6381417481864;1234.1176059117774;13867.306634668226;14239.07446016016;14266.64333391663;15433.5369938902;15614.141222232773;5282.920756188661;5455.236266922634;5486.177073439016;5486.972565137149;5509.201165831012;11472.827907581393;11483.25358851672;11486.253393529394;11492.54029838797;11497.655070347902;4488.506264310989;5553.805616803393;5556.055470570012;6088.4824789893355;6294.968525157345;5694.743736531866;5912.674802885796;5950.523958083343;5955.3151387590115;5987.820365389045;4220.894477638022;4250.787460626956;4262.2754131797665;4320.416743739618;4330.242207613655;1079.438691880218;1114.7603265298;1244.7386048929645;1245.2777389114474;1259.7102666386781;774.7443343696567;779.528385326869;799.1768478467188;801.7354273089867;814.1370147643516;4539.546045395434;5988.6406815590335;6090.817275481742;6093.299270583851;6214.5028397728975;576.3170642788225;593.6675461741438;603.57146426038;604.6885853785304;606.342118801097;3495.2310491691787;3667.669909708139;4137.069159439162;4143.295639741251;4385.144896745104;13733;14196.71606567863;14536.127832330094;14563.563093107225;14566.41734330615;13905.79141312881;14266.144031358152;14401.495947641783;14406.639834004023;14428.85571144296;10262.948685256675;10376.481175941173;10418.635347762944;10554.683359499228;10582.629607963838;6042.758289668364;6388.329225431263;6428.067930150196;6555.934440655723;6647.102641143577;12467.376631168225;12520.186187897758;12528.686782830502;12536.74926501483;12742.10805243624;13014.414351354022;13126.409311581192;13198.934005330297;13240.470381184645;13358.732825343634;8270.793230169294;8350.498970061815;8375.371847111614;8387.287080598218;8422.831543368975;10154.904481360445;10214.846777298495;10354.792904141725;10355.741106472398;10420.947895260475;8744.387892847439;8761.474311541328;8767.649294028422;8850.867236991322;8871.334649901411;8558.229759321692;8603.05366409707;8629.180227878342;8717.956410217908;8846.380753347205;7721.845563088819;7789.221077892392;7904.960475197588;7905;7940.801479963047;5938.940610594012;5942.405759424023;6076.179715738319;6083.239595050619;6188.412100850412;5772.5959182856795;5779.826605201851;5875.706214689334;6110.847228819315;6121.296050954197 19:06:41 INFO - PID 1636 | |4;prototype.html;347.7322461704488;382.57343062485336;396.8075483390557;397.6460949754719;411.5349654889974;4829.734364609945;5741.511971482419;5857.853553661155;5965.045592705168;6014.466690644601;5758.568107391941;5780.612652963279;5859.780957710453;5910.46805787479;5919.378465261139;3686.4286035664513;4464.129494748526;4482.731036137842;4484.529124441936;4493.730376177424;4216.578342165774;4449.5327990560845;4481.439820022497;4488.304312831517;4491.797869095883;3940.1827693199357;4015.698822588303;4177.456930599018;4398.912021759563;4421.867343979686;3996.140829721595;4074.246264441088;4402.669799764998;4438.689291749579;4440.533743956903;3639.94540081899;3813.389857622767;3832.137769001952;3835.9232815343817;3839.4816699745734;4955.752212389366;5647.29408823897;5720.914186287209;5733.397993210695;5745.885082298372;5149.588032957355;5166.896662066738;5184.248283998837;5203.531682148625;5207.765650545734;6104.877902441971;6261.123442718023;6290.779822706229;6326.9683651581445;6336.619802811846;4281.785910704452;4293.711886434088;4311.353297005443;4313.870583882489;4331.696781225315;6395.936040639581;6494.4479719223455;6502.50442398251;6648.734050637969;6722.83192920176;4042.797860106983;4194.51094861326;4213.852515162016;4325.199838029975;4349.412829268008;3643.362411577963;4381.38660587518;4381.868543943687;4397.758123303249;4403.823847046082;4496.797644105957;5126.92309615356;5144.511271429208;5147.639665223398;5200.76596553156;6116.296625887991;6225.657588832567;6279.654618995999;6376.553641245161;6386.8722625547225;1474.3807600807686;1496.513633069257;1498.3931507739383;1505.157112017274;1512.1834209526953;802.6027116577332;925.1396201532639;926.4672813132341;998.7852611688522;1006.4766321512773;5925;5936.7031648417405;5956.55325850563;5957.463828255478;5979.222701048923;6484.157059582224;6486.286508484063;6545.363659085228;6551.312112228243;6557.639329836905;4278.679099067583;4869.70781753096;5439.428859969726;5907.966105931445;5934.554908381889;2689.6503454551175;2797.2587264374956;3177.5551422800827;3291.374638818617;3421.8973430797114;6293.465055470245;6676.632785196861;6765.560238584476;6768.289329620417;6773.153355830521;5284.9735751320995;6256.624602523863;6714.664266786641;6720.2943690912725;6748.561343512754;5272.288241087557;6445.355464453517;6583.901241481286;6619.271880093186;6620.404163625297;5678.943210567842;5787.9710601448405;5960.612560183574;6462.612243265419;6644.933550664625;5436.53789427903;6458.869697802959;6471.514636402195;6493.772717954849;6503.122078519339;5863.824085277449;6710.496712746466;6723.2604413515455;6744.190697116182;6799.93200067993;4333.891652708708;4460.1971645104195;4501.369808226917;4768.877849357003;4805.3512775774825;4792.514320560637;6202.162708034358;6287.30839607652;6440.1394730794755;6501.804945851632;4927.334809800631;5395.974764794676;5423.132298832168;6052.243469566304;6368.248731598847;5257.710825904459;5495.587830912617;6257.092721555258;6348.238211414659;6353.555251132377;5764.847030593814;6173.9073913892225;6179.598326108789;6191.597546159485;6191.854506916235;5870.767138900708;5954.76180952757;6020.862593779146;6346.4605508532295;6348;4702.694324868872;6130.5402094842175;6137.018077107641;6164.47601953839;6219.284782250099 19:06:41 INFO - PID 1636 | |5;yui.html;698.9056477375552;700.3206889317361;720.9149389595683;723.7141329174976;726.2773540327372;14963.177025263607;15738.685226295467;15768.211589420525;15770.763438548402;15794.60513487162;14323.140611563287;14664.340104695288;14726.484573039947;14898.627534311621;15127.319270632821;922.7462447826852;936.180841763457;954.4654993203793;955.2024059909984;956.8803899512561;959.6593209410663;964.0744884910495;965.4979410706428;966.4732720667218;967.7241986033981;978.8825340959095;1020.2552136940038;1023.7798873242251;1026.6714651311584;1036.1037702387418;1019.3323373190498;1020.9943205942141;1021.3310281765456;1023.43199524264;1024.5645600619707;756.9810754731176;757.6779868555842;759.3090287838096;760.2017881224747;761.5202422473861;15950;15958.202089895458;16167.281525249258;16374.426895058616;16646.417375391924;14613.561593152222;14631.41474341025;14636.255647393942;14829.332680029425;14855.710406159478;11391.658250252505;11499.137564682682;11715.527143784631;11722.35527046012;11997.28016319015;2979.567962645426;3122.3755248950074;3143.701348371924;3149.4016136934138;3149.464591019509;97720;102134.97865021329;103219.45170822369;103625;106907;9703.076877074347;10843.07833834117;11338.263012904135;11356.03473704728;11505.539778408938;25040.749592504206;25087.372815679388;25110.623340649905;25135.994560217754;25153.496930061297;7772.92227077722;7808.882866757003;7859.921400785919;7938.722144724907;8141.348692104618;3923.2741942740113;4000.5199376075057;4010.057636455431;4016.377461493473;4019.577944315863;4066.410370496321;4153.252414565407;4205.495340559091;4214.199302132585;4254.127903779718;742.3207764895061;790.7034861926777;798.1021350980147;847.3814115695453;851.7359618518279;525.9552938000236;557.1948534367881;567.3220501500749;568.8577855536116;591.6509259536894;9025.277977761763;9098.954505227432;10040.949795251123;10117.544710488046;10158.492075396201;527.5246351041002;537.8386484054768;582.7086456771614;584.4652143288843;595.3808039638814;4432.578905004019;4732.52674732524;4755.120302744003;4773.236282194832;4839.491853355347;91606;94159;94526.52736736409;95503;96787.51606241924;83165.3366932658;85392;85743.5712821432;86770.56614716887;87915;47628;51578.74210628998;56574;69084.6545767268;69601.30398695948;9773.853392199266;10345.431001295;10368.034381046784;10493.685189444328;10575.788484230427;31640.525392118678;32006.559704812873;34361;34523.48214776831;35454.29091418105;45776;45806.29155502709;46544.7672761634;47048.529514704416;47049.529504704515;12786.936065319614;13369.197848129143;13657.726845463234;13881.930590347387;14231.292244930675;14311.284435778378;14837.554873353816;14892.255387230813;15179.696406072038;15443.2278386078;13648.385822637807;13812;13859.514916977858;13990.930045349709;14427.855721442651;13803.378847951666;14074;14116.294185290899;14387.640308992359;14555.854441455449;13566.253856037618;13630.522931697344;13761.417601419467;13785.862141378859;13897.37461814241;8998.20691504933;9530.85703714431;9969.252306077218;9971.750706232402;10300.896991029993;8927;9498.905010950079;9980.2514811388;11102;11238 19:06:41 INFO - PID 1636 | __end_tp_report 19:06:41 INFO - PID 1636 | __start_cc_report 19:06:41 INFO - PID 1636 | _x_x_mozilla_cycle_collect,1475 19:06:41 INFO - PID 1636 | __end_cc_report 19:06:41 INFO - PID 1636 | __startTimestamp1490407601390__endTimestamp 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | ------- Summary: start ------- 19:06:41 INFO - PID 1636 | Number of tests: 6 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:5965.32 Median:6201.53 stddev:661.78 (10.7%) stddev-sans-first:626.35 19:06:41 INFO - PID 1636 | Values: 3167.1 3448.8 3472.0 3514.4 3530.2 6051.1 6075.8 6108.1 6121.0 6123.9 6042.8 6104.1 6108.1 6112.5 6142.4 5052.6 5374.1 5477.8 5482.2 5622.6 5550.3 5555.8 5581.2 5600.2 5603.2 5326.1 5383.2 5392.2 5392.5 5395.3 5478.8 5492.9 5498.6 5579.5 5596.2 4460.8 5432.0 5439.1 5504.5 5542.7 5960.6 5970.1 6021.9 6162.0 6192.1 6199.5 6267.9 6271.3 6275.1 6281.7 5706.7 6367.4 6441.1 6517.4 6523.4 6158.5 6182.7 6223.6 6224.8 6231.0 6428.7 6670.9 6678.0 6694.1 6694.7 5950.9 6202.3 6222.3 6234.7 6239.7 5868.8 5948.9 6343.3 6352.8 6355.7 6266.3 6268.9 6332.8 6409.0 6409.0 6421.7 6423.0 6460.0 6467.8 6477.4 5238.6 5240.9 5242.0 5251.1 5287.7 3978.4 4499.9 4589.5 4638.8 4649.7 6092.7 6094.2 6094.9 6101.8 6108.7 6173.8 6314.7 6326.4 6337.7 6352.5 6086.0 6102.2 6109.2 6110.9 6141.9 5690.6 5699.2 5708.2 5735.2 5760.3 6584.5 6590.8 6611.8 6614.8 6616.6 6457.4 6472.7 6473.3 6480.9 6488.4 6413.0 6483.6 6508.6 6552.0 6602.9 6344.3 6357.3 6357.9 6361.4 6365.8 6158.5 6242.3 6396.4 6441.7 6463.2 6304.3 6321.1 6327.1 6327.5 6337.8 6309.8 6317.4 6317.9 6318.9 6326.8 5619.8 6125.8 6201.5 6213.0 6234.0 5870.9 6308.6 6312.4 6312.8 6315.1 6274.2 6280.4 6282.8 6287.3 6294.8 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:53830.02 Median:32856.59 stddev:110747.47 (337.1%) stddev-sans-first:110986.32 19:06:41 INFO - PID 1636 | Values: 706.2 721.7 753.6 1145.1 1164.8 27606.8 28041.4 28050.7 28080.0 28850.0 29063.0 29264.6 37306.3 37772.0 37918.6 8493.6 8660.2 8663.1 8664.3 8664.6 6095.1 8480.7 8618.8 8629.2 8641.4 6273.3 7751.2 8345.9 8442.5 8643.2 6892.0 8632.7 8728.1 8742.8 8801.6 5137.7 8394.7 8504.1 8524.5 8548.7 27126.3 27425.5 27446.9 27447.0 27464.6 2946.1 2949.0 2968.4 2971.5 2988.5 27815.9 27966.3 28148.9 28338.7 28738.3 2780.5 2792.6 2821.5 2823.7 2827.6 348064.5 349490.3 352231.0 352459.0 353677.0 52022.0 52149.5 52182.0 52210.0 52337.7 54531.5 54535.9 54577.0 54608.0 54660.5 14254.2 14676.0 14720.2 14737.9 14770.2 11883.6 11891.6 11894.9 11901.5 11913.5 10620.5 10794.8 12566.1 12796.2 12827.9 5343.5 5569.9 5580.9 5599.2 5604.4 953.3 967.2 974.7 982.9 983.5 23937.9 24151.3 24171.9 24206.2 24341.8 1071.4 1076.8 1117.2 1125.0 1127.1 12134.5 12258.4 12258.7 12302.3 12523.6 450498.0 451327.0 452339.0 452851.0 452952.0 55886.0 56013.0 56078.7 56131.4 56183.4 1447.7 1475.3 1526.4 1532.9 1541.3 22205.6 23580.8 23582.4 23614.3 23638.3 38780.6 38894.2 38903.8 38910.6 38914.4 437529.0 437962.0 439049.0 439388.0 439623.0 31877.0 32802.7 32910.5 32927.5 33897.5 41444.0 41504.0 41899.6 41901.4 41926.2 24167.8 27336.7 29060.1 29246.3 29582.4 29378.3 29444.7 29473.9 30620.0 30707.5 35592.5 36777.6 36825.8 36929.4 36970.0 28726.0 29935.6 30356.0 30462.5 30695.0 19822.1 22011.6 22062.7 23822.4 24393.3 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:40197.40 Median:23400.41 stddev:114256.31 (488.3%) stddev-sans-first:114537.73 19:06:41 INFO - PID 1636 | Values: 388.7 429.1 439.4 441.6 461.7 18125.7 21021.0 21903.6 22828.9 23050.9 19217.8 22860.7 23327.0 23473.8 23751.3 4629.4 6048.2 6179.9 6282.2 6286.8 3641.2 3869.8 3878.3 5909.6 6208.5 6177.3 6187.8 6189.3 6202.4 6208.8 5876.3 6080.7 6099.3 6151.2 6152.1 5300.3 5460.1 5463.6 5472.1 5478.9 23039.7 27707.6 28185.6 28412.0 28737.7 2030.2 2048.4 2064.2 2075.9 2141.7 15610.5 16271.7 16407.9 16785.3 16792.0 1633.9 1713.7 1715.8 1745.1 1811.0 158073.0 164644.0 166101.0 166565.2 169940.2 10145.7 12274.3 12407.3 12427.9 12523.9 16634.8 16636.7 16646.5 16665.2 16675.9 8649.3 8850.5 8893.5 8960.7 9047.2 8402.5 8424.6 8607.4 8623.9 8642.1 6439.8 7541.9 7836.5 7854.3 7876.8 1976.8 2007.8 2095.1 2095.9 2286.6 819.0 934.5 937.9 959.7 983.1 20152.3 20831.0 20855.9 20884.9 20908.4 914.3 922.4 953.8 974.9 999.8 10031.6 10764.0 10801.8 10805.0 11012.6 567313.0 625920.0 719290.0 724620.0 730929.0 141607.3 154726.5 157941.2 162469.0 164108.0 1191.2 1210.5 1249.5 1250.6 1255.7 20380.0 21645.1 21753.0 21860.5 21943.9 60161.4 60290.7 60803.0 61627.8 61881.7 83798.0 85416.3 86578.0 87225.6 88088.6 7793.8 8390.0 8467.3 8777.0 8848.8 8979.9 11702.6 12894.2 13153.9 13203.0 10925.8 10988.7 11346.8 11349.8 11445.8 9235.9 9356.4 10815.4 10892.0 10997.0 11534.9 11687.8 11751.4 11924.3 11981.5 6487.6 8381.1 8555.8 8633.5 8638.8 11103.5 11375.4 11682.2 11801.6 11886.7 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:6804.07 Median:2519.77 stddev:4253.78 (168.8%) stddev-sans-first:4246.10 19:06:41 INFO - PID 1636 | Values: 1367.4 1432.1 1448.7 1448.7 1460.7 9116.9 10914.7 10922.0 10930.3 10933.7 10606.5 10722.0 10902.5 11038.5 11042.3 2478.0 2510.6 2522.0 2523.6 2527.6 2361.7 2365.8 2367.6 2381.1 2442.5 2402.4 2513.4 2516.7 2517.5 2517.5 2450.5 2463.6 2468.4 2496.3 2502.2 2379.2 2450.8 2470.9 2484.7 2488.3 11063.4 11158.7 11172.6 11177.5 11185.0 6258.0 6505.5 6509.7 6522.8 6525.1 3965.1 4034.2 4084.1 4132.7 4136.6 1166.7 1172.7 1210.9 1226.6 1234.1 13867.3 14239.1 14266.6 15433.5 15614.1 5282.9 5455.2 5486.2 5487.0 5509.2 11472.8 11483.3 11486.3 11492.5 11497.7 4488.5 5553.8 5556.1 6088.5 6295.0 5694.7 5912.7 5950.5 5955.3 5987.8 4220.9 4250.8 4262.3 4320.4 4330.2 1079.4 1114.8 1244.7 1245.3 1259.7 774.7 779.5 799.2 801.7 814.1 4539.5 5988.6 6090.8 6093.3 6214.5 576.3 593.7 603.6 604.7 606.3 3495.2 3667.7 4137.1 4143.3 4385.1 13733.0 14196.7 14536.1 14563.6 14566.4 13905.8 14266.1 14401.5 14406.6 14428.9 10262.9 10376.5 10418.6 10554.7 10582.6 6042.8 6388.3 6428.1 6555.9 6647.1 12467.4 12520.2 12528.7 12536.7 12742.1 13014.4 13126.4 13198.9 13240.5 13358.7 8270.8 8350.5 8375.4 8387.3 8422.8 10154.9 10214.8 10354.8 10355.7 10420.9 8744.4 8761.5 8767.6 8850.9 8871.3 8558.2 8603.1 8629.2 8718.0 8846.4 7721.8 7789.2 7905.0 7905.0 7940.8 5938.9 5942.4 6076.2 6083.2 6188.4 5772.6 5779.8 5875.7 6110.8 6121.3 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5065.20 Median:5743.70 stddev:1586.57 (27.6%) stddev-sans-first:1551.00 19:06:41 INFO - PID 1636 | Values: 347.7 382.6 396.8 397.6 411.5 4829.7 5741.5 5857.9 5965.0 6014.5 5758.6 5780.6 5859.8 5910.5 5919.4 3686.4 4464.1 4482.7 4484.5 4493.7 4216.6 4449.5 4481.4 4488.3 4491.8 3940.2 4015.7 4177.5 4398.9 4421.9 3996.1 4074.2 4402.7 4438.7 4440.5 3639.9 3813.4 3832.1 3835.9 3839.5 4955.8 5647.3 5720.9 5733.4 5745.9 5149.6 5166.9 5184.2 5203.5 5207.8 6104.9 6261.1 6290.8 6327.0 6336.6 4281.8 4293.7 4311.4 4313.9 4331.7 6395.9 6494.4 6502.5 6648.7 6722.8 4042.8 4194.5 4213.9 4325.2 4349.4 3643.4 4381.4 4381.9 4397.8 4403.8 4496.8 5126.9 5144.5 5147.6 5200.8 6116.3 6225.7 6279.7 6376.6 6386.9 1474.4 1496.5 1498.4 1505.2 1512.2 802.6 925.1 926.5 998.8 1006.5 5925.0 5936.7 5956.6 5957.5 5979.2 6484.2 6486.3 6545.4 6551.3 6557.6 4278.7 4869.7 5439.4 5908.0 5934.6 2689.7 2797.3 3177.6 3291.4 3421.9 6293.5 6676.6 6765.6 6768.3 6773.2 5285.0 6256.6 6714.7 6720.3 6748.6 5272.3 6445.4 6583.9 6619.3 6620.4 5678.9 5788.0 5960.6 6462.6 6644.9 5436.5 6458.9 6471.5 6493.8 6503.1 5863.8 6710.5 6723.3 6744.2 6799.9 4333.9 4460.2 4501.4 4768.9 4805.4 4792.5 6202.2 6287.3 6440.1 6501.8 4927.3 5396.0 5423.1 6052.2 6368.2 5257.7 5495.6 6257.1 6348.2 6353.6 5764.8 6173.9 6179.6 6191.6 6191.9 5870.8 5954.8 6020.9 6346.5 6348.0 4702.7 6130.5 6137.0 6164.5 6219.3 19:06:41 INFO - PID 1636 | 19:06:41 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:18680.87 Median:34988.89 stddev:26154.26 (74.8%) stddev-sans-first:26192.78 19:06:41 INFO - PID 1636 | Values: 698.9 700.3 720.9 723.7 726.3 14963.2 15738.7 15768.2 15770.8 15794.6 14323.1 14664.3 14726.5 14898.6 15127.3 922.7 936.2 954.5 955.2 956.9 959.7 964.1 965.5 966.5 967.7 978.9 1020.3 1023.8 1026.7 1036.1 1019.3 1021.0 1021.3 1023.4 1024.6 757.0 757.7 759.3 760.2 761.5 15950.0 15958.2 16167.3 16374.4 16646.4 14613.6 14631.4 14636.3 14829.3 14855.7 11391.7 11499.1 11715.5 11722.4 11997.3 2979.6 3122.4 3143.7 3149.4 3149.5 97720.0 102135.0 103219.5 103625.0 106907.0 9703.1 10843.1 11338.3 11356.0 11505.5 25040.7 25087.4 25110.6 25136.0 25153.5 7772.9 7808.9 7859.9 7938.7 8141.3 3923.3 4000.5 4010.1 4016.4 4019.6 4066.4 4153.3 4205.5 4214.2 4254.1 742.3 790.7 798.1 847.4 851.7 526.0 557.2 567.3 568.9 591.7 9025.3 9099.0 10040.9 10117.5 10158.5 527.5 537.8 582.7 584.5 595.4 4432.6 4732.5 4755.1 4773.2 4839.5 91606.0 94159.0 94526.5 95503.0 96787.5 83165.3 85392.0 85743.6 86770.6 87915.0 47628.0 51578.7 56574.0 69084.7 69601.3 9773.9 10345.4 10368.0 10493.7 10575.8 31640.5 32006.6 34361.0 34523.5 35454.3 45776.0 45806.3 46544.8 47048.5 47049.5 12786.9 13369.2 13657.7 13881.9 14231.3 14311.3 14837.6 14892.3 15179.7 15443.2 13648.4 13812.0 13859.5 13990.9 14427.9 13803.4 14074.0 14116.3 14387.6 14555.9 13566.3 13630.5 13761.4 13785.9 13897.4 8998.2 9530.9 9969.3 9971.8 10300.9 8927.0 9498.9 9980.3 11102.0 11238.0 19:06:41 INFO - PID 1636 | -------- Summary: end -------- 19:06:41 INFO - PID 1636 | 19:06:42 INFO - TEST-INFO | 1636: exit 0 19:06:42 INFO - TEST-OK | dromaeo_css | took 1120922ms 19:06:42 INFO - TEST-START | kraken 19:06:42 INFO - operating with platform_type : mac_ 19:06:42 INFO - Initialising browser for kraken test... 19:06:42 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpsFwtwU/profile http://localhost:49242/getInfo.html) 19:06:44 INFO - PID 1646 | __metrics Screen width/height:1600/1200 19:06:44 INFO - PID 1646 | colorDepth:24 19:06:44 INFO - PID 1646 | Browser inner width/height: 1024/689 19:06:44 INFO - PID 1646 | __metrics 19:06:44 INFO - PID 1646 | 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" 19:06:44 INFO - PID 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:06:45 INFO - TEST-INFO | 1646: exit 0 19:06:45 INFO - Browser initialized. 19:06:45 INFO - Running cycle 1/1 for kraken test... 19:06:45 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpsFwtwU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:57 INFO - PID 1647 | RSS: Main: 234115072 19:06:57 INFO - PID 1647 | 19:07:00 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:01 INFO - PID 1647 | RSS: Main: 285417472 19:07:01 INFO - PID 1647 | 19:07:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 19:07:04 INFO - PID 1647 | RSS: Main: 287653888 19:07:04 INFO - PID 1647 | 19:07:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 19:07:08 INFO - PID 1647 | RSS: Main: 380223488 19:07:08 INFO - PID 1647 | 19:07:10 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 19:07:11 INFO - PID 1647 | RSS: Main: 281743360 19:07:11 INFO - PID 1647 | 19:07:13 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:13 INFO - PID 1647 | RSS: Main: 284065792 19:07:13 INFO - PID 1647 | 19:07:16 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:17 INFO - PID 1647 | RSS: Main: 325009408 19:07:17 INFO - PID 1647 | 19:07:20 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:21 INFO - PID 1647 | RSS: Main: 339140608 19:07:21 INFO - PID 1647 | 19:07:24 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 19:07:25 INFO - PID 1647 | RSS: Main: 349724672 19:07:25 INFO - PID 1647 | 19:07:26 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:27 INFO - PID 1647 | RSS: Main: 303243264 19:07:27 INFO - PID 1647 | 19:07:29 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:29 INFO - PID 1647 | RSS: Main: 305664000 19:07:29 INFO - PID 1647 | 19:07:32 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:32 INFO - PID 1647 | RSS: Main: 307519488 19:07:32 INFO - PID 1647 | 19:07:35 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:36 INFO - PID 1647 | RSS: Main: 326365184 19:07:36 INFO - PID 1647 | 19:07:39 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:07:40 INFO - PID 1647 | RSS: Main: 309743616 19:07:40 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 19:07:42 INFO - PID 1647 | RSS: Main: 375115776 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | __start_tp_report 19:07:42 INFO - PID 1647 | _x_x_mozilla_page_load 19:07:42 INFO - PID 1647 | _x_x_mozilla_page_load_details 19:07:42 INFO - PID 1647 | |i|pagename|runs| 19:07:42 INFO - PID 1647 | |0;ai-astar;112;112;112;112;111;111;112;112;112;112 19:07:42 INFO - PID 1647 | |1;audio-beat-detection;146;128;141;153;113;110;110;107;150;145 19:07:42 INFO - PID 1647 | |2;audio-dft;185;185;183;182;186;183;177;182;183;184 19:07:42 INFO - PID 1647 | |3;audio-fft;81;63;59;90;63;61;58;59;78;59 19:07:42 INFO - PID 1647 | |4;audio-oscillator;88;98;123;104;72;68;84;70;68;84 19:07:42 INFO - PID 1647 | |5;imaging-gaussian-blur;88;87;88;87;88;87;88;87;89;86 19:07:42 INFO - PID 1647 | |6;imaging-darkroom;107;152;94;95;95;94;101;94;94;94 19:07:42 INFO - PID 1647 | |7;imaging-desaturate;84;84;84;84;86;84;83;98;127;126 19:07:42 INFO - PID 1647 | |8;json-parse-financial;63;63;61;60;62;62;63;61;61;63 19:07:42 INFO - PID 1647 | |9;json-stringify-tinderbox;53;61;66;60;60;57;57;58;58;58 19:07:42 INFO - PID 1647 | |10;stanford-crypto-aes;106;107;105;80;82;83;83;82;83;83 19:07:42 INFO - PID 1647 | |11;stanford-crypto-ccm;132;158;148;129;144;144;151;152;206;161 19:07:42 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;229;246;187;189;183;232;185;186;183;183 19:07:42 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;69;67;67;68;68;69;68;70;67;68 19:07:42 INFO - PID 1647 | __end_tp_report 19:07:42 INFO - PID 1647 | __start_cc_report 19:07:42 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2264 19:07:42 INFO - PID 1647 | __end_cc_report 19:07:42 INFO - PID 1647 | __startTimestamp1490407662482__endTimestamp 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | ------- Summary: start ------- 19:07:42 INFO - PID 1647 | Number of tests: 14 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:07:42 INFO - PID 1647 | Values: 112.0 112.0 112.0 112.0 111.0 111.0 112.0 112.0 112.0 112.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:143.00 stddev:18.71 (13.1%) stddev-sans-first:18.97 19:07:42 INFO - PID 1647 | Values: 146.0 128.0 141.0 153.0 113.0 110.0 110.0 107.0 150.0 145.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:2.49 (1.4%) stddev-sans-first:2.54 19:07:42 INFO - PID 1647 | Values: 185.0 185.0 183.0 182.0 186.0 183.0 177.0 182.0 183.0 184.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:67.10 Median:63.00 stddev:11.48 (18.2%) stddev-sans-first:11.02 19:07:42 INFO - PID 1647 | Values: 81.0 63.0 59.0 90.0 63.0 61.0 58.0 59.0 78.0 59.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:78.00 stddev:18.10 (23.2%) stddev-sans-first:19.18 19:07:42 INFO - PID 1647 | Values: 88.0 98.0 123.0 104.0 72.0 68.0 84.0 70.0 68.0 84.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 19:07:42 INFO - PID 1647 | Values: 88.0 87.0 88.0 87.0 88.0 87.0 88.0 87.0 89.0 86.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:94.00 stddev:18.09 (19.2%) stddev-sans-first:19.09 19:07:42 INFO - PID 1647 | Values: 107.0 152.0 94.0 95.0 95.0 94.0 101.0 94.0 94.0 94.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:84.00 stddev:17.68 (21.1%) stddev-sans-first:18.38 19:07:42 INFO - PID 1647 | Values: 84.0 84.0 84.0 84.0 86.0 84.0 83.0 98.0 127.0 126.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.50 stddev:1.10 (1.8%) stddev-sans-first:1.09 19:07:42 INFO - PID 1647 | Values: 63.0 63.0 61.0 60.0 62.0 62.0 63.0 61.0 61.0 63.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:3.36 (5.7%) stddev-sans-first:2.83 19:07:42 INFO - PID 1647 | Values: 53.0 61.0 66.0 60.0 60.0 57.0 57.0 58.0 58.0 58.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:82.50 stddev:11.50 (13.9%) stddev-sans-first:10.51 19:07:42 INFO - PID 1647 | Values: 106.0 107.0 105.0 80.0 82.0 83.0 83.0 82.0 83.0 83.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:151.50 stddev:21.36 (14.1%) stddev-sans-first:21.32 19:07:42 INFO - PID 1647 | Values: 132.0 158.0 148.0 129.0 144.0 144.0 151.0 152.0 206.0 161.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.30 Median:188.00 stddev:24.85 (13.2%) stddev-sans-first:24.09 19:07:42 INFO - PID 1647 | Values: 229.0 246.0 187.0 189.0 183.0 232.0 185.0 186.0 183.0 183.0 19:07:42 INFO - PID 1647 | 19:07:42 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 19:07:42 INFO - PID 1647 | Values: 69.0 67.0 67.0 68.0 68.0 69.0 68.0 70.0 67.0 68.0 19:07:42 INFO - PID 1647 | -------- Summary: end -------- 19:07:42 INFO - PID 1647 | 19:07:43 INFO - TEST-INFO | 1647: exit 0 19:07:43 INFO - TEST-OK | kraken | took 61392ms 19:07:43 INFO - SUITE-END | took 1182s 19:07:43 INFO - Completed test suite (00:19:42) 19:07:43 INFO - javascript benchmark 19:07:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7527.241437491563, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3167.144870884861, 3448.8275586220684, 3471.975767148689, 3514.4376899696044, 3530.24099818539, 6051.062085376768, 6075.787347442841, 6108.114323423112, 6121.0262130507535, 6123.926141497245, 6042.7891611279465, 6104.114903339024, 6108.129596501696, 6112.510999120071, 6142.406692085125, 5052.595792336604, 5374.059539580578, 5477.808276710316, 5482.150266708666, 5622.606417550773, 5550.306211723535, 5555.777768889239, 5581.190727344532, 5600.18797274395, 5603.2435621191225, 5326.147816349366, 5383.192521121824, 5392.164214546712, 5392.514673679388, 5395.271638328814, 5478.780848766045, 5492.945070549284, 5498.615096943216, 5579.46995035471, 5596.188552659883, 4460.799264033127, 5432.049391356536, 5439.075357189287, 5504.504594586467, 5542.695151766651, 5960.552958528085, 5970.104484327342, 6021.879562408727, 6161.9691901540655, 6192.102145188967, 6199.535034872358, 6267.937320626781, 6271.341509141565, 6275.09011193379, 6281.7173227204885, 5706.71466426677, 6367.395097465734, 6441.130447389589, 6517.4134327910715, 6523.4455071319, 6158.5381096417495, 6182.721777520022, 6223.564350495421, 6224.813255602339, 6231.0, 6428.742850286029, 6670.933290667128, 6678.031685405589, 6694.09629699994, 6694.732210711517, 5950.9107363389585, 6202.317745048041, 6222.346653601307, 6234.688265586702, 6239.688015599202, 5868.823935281948, 5948.910766338508, 6343.302236753953, 6352.809415717536, 6355.745770169141, 6266.279377871603, 6268.905966410507, 6332.8416789579705, 6408.967955160194, 6409.0, 6421.678916054178, 6422.967885160544, 6460.0, 6467.838304042437, 6477.4494167996245, 5238.607104467104, 5240.9213861792105, 5242.0, 5251.0548101341365, 5287.68273903567, 3978.403239514038, 4499.932501012488, 4589.472210695746, 4638.79125439356, 4649.744264065509, 6092.695365231721, 6094.177286066413, 6094.939050609437, 6101.84745381369, 6108.664023478751, 6173.753049877954, 6314.684265786692, 6326.367363263729, 6337.714802833883, 6352.523560732871, 6086.0, 6102.216595924226, 6109.20580324555, 6110.938890611126, 6141.877162456726, 5690.573207009491, 5699.230603868507, 5708.20085188074, 5735.1684005818925, 5760.279965004374, 6584.473242140713, 6590.80227593173, 6611.768588099393, 6614.8346291343105, 6616.603003819786, 6457.354264573505, 6472.708728107341, 6473.255575608677, 6480.935190648033, 6488.351164883663, 6413.005984072476, 6483.578567393104, 6508.60948343101, 6551.967240163769, 6602.933970660232, 6344.333844946214, 6357.3006969234275, 6357.904631430625, 6361.4274715274, 6365.809025729235, 6158.507319414526, 6242.250929888442, 6396.360363963567, 6441.71012304438, 6463.192100987377, 6304.338044505261, 6321.11504389395, 6327.082573026977, 6327.493800496041, 6337.7781777637565, 6309.779157729457, 6317.368263173646, 6317.9052314214405, 6318.873622527615, 6326.810195694136, 5619.7917447747295, 6125.84596676928, 6201.534884883562, 6213.00591905293, 6233.96883015582, 5870.9412905870395, 6308.589941653778, 6312.43188113072, 6312.810615681353, 6315.147455093687, 6274.1843560336865, 6280.371962803683, 6282.780102696383, 6287.30839607652, 6294.842628934313], "value": 5922.387880339127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.2266817834471, 721.7365661533539, 753.6156560154319, 1145.106816682987, 1164.8019836627773, 27606.75769590368, 28041.43917121659, 28050.719492805067, 28080.0, 28850.0, 29063.0, 29264.56103158449, 37306.25387492255, 37772.0, 37918.62081379185, 8493.617787199591, 8660.220580147785, 8663.133686631349, 8664.26353759928, 8664.566771661423, 6095.08573713942, 8480.66077356905, 8618.827623447527, 8629.180227878342, 8641.438306510088, 6273.309935907047, 7751.18612545683, 8345.874811877828, 8442.493450392996, 8643.222110010129, 6891.965540172318, 8632.65469381224, 8728.083551227157, 8742.825143497159, 8801.559922003938, 5137.666051706665, 8394.664213431455, 8504.107068757816, 8524.531150786766, 8548.70079547219, 27126.32184195391, 27425.451490970267, 27446.862765686044, 27447.0, 27464.588031179548, 2946.1161651504462, 2948.9852550737323, 2968.3766409054124, 2971.4651362754694, 2988.5068963621193, 27815.887364505397, 27966.300842478897, 28148.85925570359, 28338.716612833814, 28738.281542961384, 2780.4856101621267, 2792.581112833071, 2821.534446816273, 2823.7035111313426, 2827.6465441819773, 348064.51935480576, 349490.27293771843, 352231.0, 352459.0, 353677.0, 52022.0, 52149.47850521522, 52182.0, 52210.0, 52337.73831130896, 54531.454685452634, 54535.90928181414, 54577.0, 54608.0, 54660.453395465534, 14254.216018118896, 14675.972681912377, 14720.19038952868, 14737.852621473863, 14770.187639679705, 11883.64349069512, 11891.643250702493, 11894.92945634897, 11901.464434100486, 11913.463894124785, 10620.522076506575, 10794.784104318027, 12566.05754568411, 12796.232226066466, 12827.935860320826, 5343.545798607084, 5569.888602227933, 5580.944190558123, 5599.188117722907, 5604.355499117572, 953.2612225525135, 967.1634036558327, 974.6783561424713, 982.8820541535061, 983.5426526664992, 23937.940145216784, 24151.275461736175, 24171.879140604186, 24206.15278465245, 24341.756582433947, 1071.3518321415588, 1076.8115579773507, 1117.1621284036971, 1124.9775004499863, 1127.1039523578775, 12134.453949572291, 12258.44836982338, 12258.693532661577, 12302.323372214614, 12523.624291271277, 450498.0, 451327.0, 452339.0, 452851.0, 452952.0, 55886.0, 56013.0, 56078.719606401704, 56131.43868561262, 56183.43816561864, 1447.6815100677834, 1475.2771142140489, 1526.419960415013, 1532.869706074974, 1541.267897748583, 22205.555888882456, 23580.764192357856, 23582.410439739142, 23614.29157125289, 23638.29085127449, 38780.6121938777, 38894.2221155581, 38903.80548097241, 38910.61089389183, 38914.416283756334, 437529.0, 437962.0, 439049.0, 439388.0, 439623.0, 31877.0, 32802.671973280914, 32910.506342404406, 32927.50608740823, 33897.49153762646, 41444.0, 41503.96240093901, 41899.58100418957, 41901.371479428446, 41926.1614767709, 24167.758322417256, 27336.72663273342, 29060.101188492376, 29246.26884327909, 29582.408351833274, 29378.265543360732, 29444.705552944197, 29473.852630737565, 30620.0, 30707.539386909662, 35592.46611300781, 36777.63222367742, 36825.81587092047, 36929.44605830968, 36970.0, 28726.0, 29935.611589914024, 30356.0, 30462.543061854532, 30695.0, 19822.07519334773, 22011.559768804214, 22062.66905996379, 23822.404439889142, 24393.26820195397], "value": 17222.996930277084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7413386352508, 429.137433758146, 439.417771452825, 441.6466826538769, 461.68374663355604, 18125.728114078298, 21021.0, 21903.561928761414, 22828.85855707214, 23050.884745576248, 19217.807821921775, 22860.65709014366, 23327.0, 23473.82630868454, 23751.287461376185, 4629.3518907353, 6048.213732214817, 6179.938200617981, 6282.24613046435, 6286.842828929291, 3641.2353405784816, 3869.7678139311593, 3878.3019056569806, 5909.645421274716, 6208.534359923001, 6177.258728952532, 6187.845303867394, 6189.2572891252885, 6202.4417802397775, 6208.751649933997, 5876.294844618652, 6080.665563394034, 6099.329073801879, 6151.200343955257, 6152.077188421727, 5300.337457817773, 5460.126379779256, 5463.562914966793, 5472.057419616059, 5478.890422191574, 23039.654405183766, 27707.584386234223, 28185.57721634177, 28412.00557980461, 28737.71262287392, 2030.187924830065, 2048.3752455501067, 2064.153696984229, 2075.937721868346, 2141.6787481877686, 15610.45363412275, 16271.674566508604, 16407.917960410123, 16785.328586856635, 16792.0, 1633.9428120015862, 1713.6658351621438, 1715.802821366298, 1745.1099939031058, 1810.97283540747, 158073.0, 164644.0, 166101.0, 166565.16717416456, 169940.15029924895, 10145.746356341151, 12274.32491212974, 12407.25556466615, 12427.875721242852, 12523.874761252271, 16634.833651663328, 16636.6672666546, 16646.50060498187, 16665.16674166287, 16675.91662041682, 8649.264812491008, 8850.468971861708, 8893.510856902933, 8960.731178064669, 9047.185753282236, 8402.495850249004, 8424.578771061544, 8607.397482176188, 8623.870641940375, 8642.135786421433, 6439.839004024937, 7541.886871696929, 7836.529808211526, 7854.253845884632, 7876.818477228461, 1976.8418526517844, 2007.7590689117108, 2095.088636443152, 2095.8952052397317, 2286.6112760830697, 818.9754307370788, 934.4673536084366, 937.8991060025494, 959.6881013670585, 983.1397527163732, 20152.29466968649, 20831.0, 20855.852928088796, 20884.895575522027, 20908.37274881756, 914.2731528434911, 922.4188761080476, 953.7901661634429, 974.8635191073255, 999.8300288950895, 10031.648892288735, 10764.03123718869, 10801.837972430263, 10804.974607110022, 11012.559497620005, 567313.0, 625920.0, 719290.0, 724620.0, 730929.0, 141607.29196353952, 154726.45273547346, 157941.2102939478, 162469.0, 164108.0, 1191.2495128069434, 1210.4794938176492, 1249.5439164704999, 1250.6060590914014, 1255.656447601058, 20380.0, 21645.13419463204, 21753.0, 21860.45348866291, 21943.890280548494, 60161.39838601558, 60290.69854650874, 60803.0, 61627.767444651756, 61881.69059154675, 83798.0, 85416.29167416741, 86578.0, 87225.56387218024, 88088.5595572018, 7793.766187014398, 8390.0, 8467.280281176169, 8776.990646075528, 8848.778780530538, 8979.910200897908, 11702.648920532398, 12894.226346419246, 13153.93423032917, 13203.0, 10925.83611245816, 10988.725281868017, 11346.829797552879, 11349.829752553553, 11445.828312575484, 9235.861462077939, 9356.4386136832, 10815.405152716676, 10891.965263299973, 10997.010269075821, 11534.94232528832, 11687.76624467489, 11751.412429378326, 11924.284542927453, 11981.520739170335, 6487.578307410004, 8381.078081411162, 8555.786105347415, 8633.525156116222, 8638.78403039929, 11103.50034248477, 11375.374354410587, 11682.24065435744, 11801.586944456903, 11886.702832429259], "value": 10283.334477187147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.3573420492369, 1432.133559196686, 1448.6885319656265, 1448.7030158817442, 1460.6859525202083, 9116.908830911689, 10914.727131821688, 10922.0, 10930.344179349244, 10933.726656833573, 10606.469676516163, 10721.98141176589, 10902.454877256145, 11038.454616353716, 11042.282251653636, 2477.9876100619517, 2510.5606518859217, 2522.016413598698, 2523.570992931206, 2527.5576774064557, 2361.6929799126087, 2365.787079162875, 2367.6093444581625, 2381.0832829360734, 2442.4992876460374, 2402.3994001499627, 2513.447041650834, 2516.6979962404444, 2517.5216708825355, 2517.5216708825355, 2450.546648869947, 2463.6304554316816, 2468.4322605800676, 2496.2885577610386, 2502.1742824867933, 2379.226751305815, 2450.799108436853, 2470.9135180268786, 2484.7018357797, 2488.2908371114327, 11063.446827658585, 11158.721031974184, 11172.553097876156, 11177.49701263445, 11185.0, 6258.0, 6505.479561635105, 6509.674516274167, 6522.804315870531, 6525.086487891699, 3965.1474932889278, 4034.172994536113, 4084.0810817566107, 4132.669386449077, 4136.58634136587, 1166.650004998497, 1172.6540670502222, 1210.939453027345, 1226.6381417481864, 1234.1176059117774, 13867.306634668226, 14239.07446016016, 14266.64333391663, 15433.5369938902, 15614.141222232773, 5282.920756188661, 5455.236266922634, 5486.177073439016, 5486.972565137149, 5509.201165831012, 11472.827907581393, 11483.25358851672, 11486.253393529394, 11492.54029838797, 11497.655070347902, 4488.506264310989, 5553.805616803393, 5556.055470570012, 6088.4824789893355, 6294.968525157345, 5694.743736531866, 5912.674802885796, 5950.523958083343, 5955.3151387590115, 5987.820365389045, 4220.894477638022, 4250.787460626956, 4262.2754131797665, 4320.416743739618, 4330.242207613655, 1079.438691880218, 1114.7603265298, 1244.7386048929645, 1245.2777389114474, 1259.7102666386781, 774.7443343696567, 779.528385326869, 799.1768478467188, 801.7354273089867, 814.1370147643516, 4539.546045395434, 5988.6406815590335, 6090.817275481742, 6093.299270583851, 6214.5028397728975, 576.3170642788225, 593.6675461741438, 603.57146426038, 604.6885853785304, 606.342118801097, 3495.2310491691787, 3667.669909708139, 4137.069159439162, 4143.295639741251, 4385.144896745104, 13733.0, 14196.71606567863, 14536.127832330094, 14563.563093107225, 14566.41734330615, 13905.79141312881, 14266.144031358152, 14401.495947641783, 14406.639834004023, 14428.85571144296, 10262.948685256675, 10376.481175941173, 10418.635347762944, 10554.683359499228, 10582.629607963838, 6042.758289668364, 6388.329225431263, 6428.067930150196, 6555.934440655723, 6647.102641143577, 12467.376631168225, 12520.186187897758, 12528.686782830502, 12536.74926501483, 12742.10805243624, 13014.414351354022, 13126.409311581192, 13198.934005330297, 13240.470381184645, 13358.732825343634, 8270.793230169294, 8350.498970061815, 8375.371847111614, 8387.287080598218, 8422.831543368975, 10154.904481360445, 10214.846777298495, 10354.792904141725, 10355.741106472398, 10420.947895260475, 8744.387892847439, 8761.474311541328, 8767.649294028422, 8850.867236991322, 8871.334649901411, 8558.229759321692, 8603.05366409707, 8629.180227878342, 8717.956410217908, 8846.380753347205, 7721.845563088819, 7789.221077892392, 7904.960475197588, 7905.0, 7940.801479963047, 5938.940610594012, 5942.405759424023, 6076.179715738319, 6083.239595050619, 6188.412100850412, 5772.5959182856795, 5779.826605201851, 5875.706214689334, 6110.847228819315, 6121.296050954197], "value": 5131.792835627103, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7322461704488, 382.57343062485336, 396.8075483390557, 397.6460949754719, 411.5349654889974, 4829.734364609945, 5741.511971482419, 5857.853553661155, 5965.045592705168, 6014.466690644601, 5758.568107391941, 5780.612652963279, 5859.780957710453, 5910.46805787479, 5919.378465261139, 3686.4286035664513, 4464.129494748526, 4482.731036137842, 4484.529124441936, 4493.730376177424, 4216.578342165774, 4449.5327990560845, 4481.439820022497, 4488.304312831517, 4491.797869095883, 3940.1827693199357, 4015.698822588303, 4177.456930599018, 4398.912021759563, 4421.867343979686, 3996.140829721595, 4074.246264441088, 4402.669799764998, 4438.689291749579, 4440.533743956903, 3639.94540081899, 3813.389857622767, 3832.137769001952, 3835.9232815343817, 3839.4816699745734, 4955.752212389366, 5647.29408823897, 5720.914186287209, 5733.397993210695, 5745.885082298372, 5149.588032957355, 5166.896662066738, 5184.248283998837, 5203.531682148625, 5207.765650545734, 6104.877902441971, 6261.123442718023, 6290.779822706229, 6326.9683651581445, 6336.619802811846, 4281.785910704452, 4293.711886434088, 4311.353297005443, 4313.870583882489, 4331.696781225315, 6395.936040639581, 6494.4479719223455, 6502.50442398251, 6648.734050637969, 6722.83192920176, 4042.797860106983, 4194.51094861326, 4213.852515162016, 4325.199838029975, 4349.412829268008, 3643.362411577963, 4381.38660587518, 4381.868543943687, 4397.758123303249, 4403.823847046082, 4496.797644105957, 5126.92309615356, 5144.511271429208, 5147.639665223398, 5200.76596553156, 6116.296625887991, 6225.657588832567, 6279.654618995999, 6376.553641245161, 6386.8722625547225, 1474.3807600807686, 1496.513633069257, 1498.3931507739383, 1505.157112017274, 1512.1834209526953, 802.6027116577332, 925.1396201532639, 926.4672813132341, 998.7852611688522, 1006.4766321512773, 5925.0, 5936.7031648417405, 5956.55325850563, 5957.463828255478, 5979.222701048923, 6484.157059582224, 6486.286508484063, 6545.363659085228, 6551.312112228243, 6557.639329836905, 4278.679099067583, 4869.70781753096, 5439.428859969726, 5907.966105931445, 5934.554908381889, 2689.6503454551175, 2797.2587264374956, 3177.5551422800827, 3291.374638818617, 3421.8973430797114, 6293.465055470245, 6676.632785196861, 6765.560238584476, 6768.289329620417, 6773.153355830521, 5284.9735751320995, 6256.624602523863, 6714.664266786641, 6720.2943690912725, 6748.561343512754, 5272.288241087557, 6445.355464453517, 6583.901241481286, 6619.271880093186, 6620.404163625297, 5678.943210567842, 5787.9710601448405, 5960.612560183574, 6462.612243265419, 6644.933550664625, 5436.53789427903, 6458.869697802959, 6471.514636402195, 6493.772717954849, 6503.122078519339, 5863.824085277449, 6710.496712746466, 6723.2604413515455, 6744.190697116182, 6799.93200067993, 4333.891652708708, 4460.1971645104195, 4501.369808226917, 4768.877849357003, 4805.3512775774825, 4792.514320560637, 6202.162708034358, 6287.30839607652, 6440.1394730794755, 6501.804945851632, 4927.334809800631, 5395.974764794676, 5423.132298832168, 6052.243469566304, 6368.248731598847, 5257.710825904459, 5495.587830912617, 6257.092721555258, 6348.238211414659, 6353.555251132377, 5764.847030593814, 6173.9073913892225, 6179.598326108789, 6191.597546159485, 6191.854506916235, 5870.767138900708, 5954.76180952757, 6020.862593779146, 6346.4605508532295, 6348.0, 4702.694324868872, 6130.5402094842175, 6137.018077107641, 6164.47601953839, 6219.284782250099], "value": 4571.051320730416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.9056477375552, 700.3206889317361, 720.9149389595683, 723.7141329174976, 726.2773540327372, 14963.177025263607, 15738.685226295467, 15768.211589420525, 15770.763438548402, 15794.60513487162, 14323.140611563287, 14664.340104695288, 14726.484573039947, 14898.627534311621, 15127.319270632821, 922.7462447826852, 936.180841763457, 954.4654993203793, 955.2024059909984, 956.8803899512561, 959.6593209410663, 964.0744884910495, 965.4979410706428, 966.4732720667218, 967.7241986033981, 978.8825340959095, 1020.2552136940038, 1023.7798873242251, 1026.6714651311584, 1036.1037702387418, 1019.3323373190498, 1020.9943205942141, 1021.3310281765456, 1023.43199524264, 1024.5645600619707, 756.9810754731176, 757.6779868555842, 759.3090287838096, 760.2017881224747, 761.5202422473861, 15950.0, 15958.202089895458, 16167.281525249258, 16374.426895058616, 16646.417375391924, 14613.561593152222, 14631.41474341025, 14636.255647393942, 14829.332680029425, 14855.710406159478, 11391.658250252505, 11499.137564682682, 11715.527143784631, 11722.35527046012, 11997.28016319015, 2979.567962645426, 3122.3755248950074, 3143.701348371924, 3149.4016136934138, 3149.464591019509, 97720.0, 102134.97865021329, 103219.45170822369, 103625.0, 106907.0, 9703.076877074347, 10843.07833834117, 11338.263012904135, 11356.03473704728, 11505.539778408938, 25040.749592504206, 25087.372815679388, 25110.623340649905, 25135.994560217754, 25153.496930061297, 7772.92227077722, 7808.882866757003, 7859.921400785919, 7938.722144724907, 8141.348692104618, 3923.2741942740113, 4000.5199376075057, 4010.057636455431, 4016.377461493473, 4019.577944315863, 4066.410370496321, 4153.252414565407, 4205.495340559091, 4214.199302132585, 4254.127903779718, 742.3207764895061, 790.7034861926777, 798.1021350980147, 847.3814115695453, 851.7359618518279, 525.9552938000236, 557.1948534367881, 567.3220501500749, 568.8577855536116, 591.6509259536894, 9025.277977761763, 9098.954505227432, 10040.949795251123, 10117.544710488046, 10158.492075396201, 527.5246351041002, 537.8386484054768, 582.7086456771614, 584.4652143288843, 595.3808039638814, 4432.578905004019, 4732.52674732524, 4755.120302744003, 4773.236282194832, 4839.491853355347, 91606.0, 94159.0, 94526.52736736409, 95503.0, 96787.51606241924, 83165.3366932658, 85392.0, 85743.5712821432, 86770.56614716887, 87915.0, 47628.0, 51578.74210628998, 56574.0, 69084.6545767268, 69601.30398695948, 9773.853392199266, 10345.431001295, 10368.034381046784, 10493.685189444328, 10575.788484230427, 31640.525392118678, 32006.559704812873, 34361.0, 34523.48214776831, 35454.29091418105, 45776.0, 45806.29155502709, 46544.7672761634, 47048.529514704416, 47049.529504704515, 12786.936065319614, 13369.197848129143, 13657.726845463234, 13881.930590347387, 14231.292244930675, 14311.284435778378, 14837.554873353816, 14892.255387230813, 15179.696406072038, 15443.2278386078, 13648.385822637807, 13812.0, 13859.514916977858, 13990.930045349709, 14427.855721442651, 13803.378847951666, 14074.0, 14116.294185290899, 14387.640308992359, 14555.854441455449, 13566.253856037618, 13630.522931697344, 13761.417601419467, 13785.862141378859, 13897.37461814241, 8998.20691504933, 9530.85703714431, 9969.252306077218, 9971.750706232402, 10300.896991029993, 8927.0, 9498.905010950079, 9980.2514811388, 11102.0, 11238.0], "value": 7391.921518214804, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 112.0, 111.0, 111.0, 112.0, 112.0, 112.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 128.0, 141.0, 153.0, 113.0, 110.0, 110.0, 107.0, 150.0, 145.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 182.0, 186.0, 183.0, 177.0, 182.0, 183.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 59.0, 90.0, 63.0, 61.0, 58.0, 59.0, 78.0, 59.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 98.0, 123.0, 104.0, 72.0, 68.0, 84.0, 70.0, 68.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 88.0, 87.0, 88.0, 87.0, 89.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 152.0, 94.0, 95.0, 95.0, 94.0, 101.0, 94.0, 94.0, 94.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 86.0, 84.0, 83.0, 98.0, 127.0, 126.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 60.0, 62.0, 62.0, 63.0, 61.0, 61.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 61.0, 66.0, 60.0, 60.0, 57.0, 57.0, 58.0, 58.0, 58.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 105.0, 80.0, 82.0, 83.0, 83.0, 82.0, 83.0, 83.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 158.0, 148.0, 129.0, 144.0, 144.0, 151.0, 152.0, 206.0, 161.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 246.0, 187.0, 189.0, 183.0, 232.0, 185.0, 186.0, 183.0, 183.0], "value": 200.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 68.0, 69.0, 68.0, 70.0, 67.0, 68.0], "value": 68.1, "unit": "score"}]}]} 19:07:43 INFO - Return code: 0 19:07:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:43 ERROR - # TBPL SUCCESS # 19:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:43 INFO - 2017-03-24 19:07:43.840 screenresolution[1651:9923] starting screenresolution argv=screenresolution get 19:07:43 INFO - 2017-03-24 19:07:43.843 screenresolution[1651:9923] Display 0: 1600x1200x32@60 19:07:43 INFO - 2017-03-24 19:07:43.849 screenresolution[1652:9926] starting screenresolution argv=screenresolution list 19:07:43 INFO - Available Modes on Display 0 19:07:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:44 INFO - Intel Iris: 19:07:44 INFO - Chipset Model: Intel Iris 19:07:44 INFO - Type: GPU 19:07:44 INFO - Bus: Built-In 19:07:44 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:44 INFO - Vendor: Intel (0x8086) 19:07:44 INFO - Device ID: 0x0a2e 19:07:44 INFO - Revision ID: 0x0009 19:07:44 INFO - Displays: 19:07:44 INFO - Display: 19:07:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:44 INFO - Display Serial Number: 1600x1200 60 19:07:44 INFO - Main Display: Yes 19:07:44 INFO - Mirror: Off 19:07:44 INFO - Online: Yes 19:07:44 INFO - Rotation: Supported 19:07:44 INFO - Return code: 0 19:07:44 INFO - Running post-action listener: _resource_record_post_action 19:07:44 INFO - [mozharness: 2017-03-25 02:07:44.088383Z] Finished run-tests step (success) 19:07:44 INFO - Running post-run listener: _resource_record_post_run 19:07:44 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 39150592; Write bytes: 513180160; Read time: 379; Write time: 1533 19:07:44 INFO - TinderboxPrint: CPU usage
53.3% 19:07:44 INFO - TinderboxPrint: I/O read bytes / time
39,150,592 / 379 19:07:44 INFO - TinderboxPrint: I/O write bytes / time
513,180,160 / 1,533 19:07:44 INFO - TinderboxPrint: CPU idle
2,245.7 (46.7%) 19:07:44 INFO - TinderboxPrint: CPU system
957.8 (19.9%) 19:07:44 INFO - TinderboxPrint: CPU user
1,610.2 (33.5%) 19:07:44 INFO - TinderboxPrint: Swap in / out
250,707,968 / 0 19:07:44 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204997120; Write bytes: 203695616; Read time: 15704; Write time: 412 19:07:44 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 37327872; Write bytes: 298662912; Read time: 357; Write time: 1085 19:07:44 INFO - Running post-run listener: _upload_blobber_files 19:07:44 INFO - Blob upload gear active. 19:07:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:44 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 19:07:44 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'] 19:07:44 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 19:07:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:46 INFO - (blobuploader) - INFO - Done attempting. 19:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:47 INFO - (blobuploader) - INFO - Done attempting. 19:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:47 INFO - (blobuploader) - INFO - Done attempting. 19:07:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:50 INFO - (blobuploader) - INFO - Done attempting. 19:07:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:50 INFO - Return code: 0 19:07:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/176eb41a713b810b00314f82318372f39a3d4e6783f013590281f88644dbdc1af9d27458ab9d2bf1cf22c30c7d63a4fcf32e0697bf4fb888c93c12f2f17e3979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6261c7724c67f5661cad501e82e40675c9644af98751ea6ca6c51705cb4978851fc90e9a3c6e84b1b477faf443245a526a77405a2b3ecda04672a8d6203202e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08b470603c5ea16373eb980870aa543d5f5f9098eaaa94e3dab6b631bf3deb23fa5f2e5cba4f4ca5861e0e10d3d4bbafed90d376838254dd93ef4c1a37d7e696", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cc1ddd5d7402792c25bfd2c74deb866ae2869c93a2b5936f936eccec163e0fb2b8599684732598fe94c6dbdae3167609426d0db34a316b6aad7b668ea2d4f9"} 19:07:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:50 INFO - Contents: 19:07:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/176eb41a713b810b00314f82318372f39a3d4e6783f013590281f88644dbdc1af9d27458ab9d2bf1cf22c30c7d63a4fcf32e0697bf4fb888c93c12f2f17e3979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6261c7724c67f5661cad501e82e40675c9644af98751ea6ca6c51705cb4978851fc90e9a3c6e84b1b477faf443245a526a77405a2b3ecda04672a8d6203202e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08b470603c5ea16373eb980870aa543d5f5f9098eaaa94e3dab6b631bf3deb23fa5f2e5cba4f4ca5861e0e10d3d4bbafed90d376838254dd93ef4c1a37d7e696", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cc1ddd5d7402792c25bfd2c74deb866ae2869c93a2b5936f936eccec163e0fb2b8599684732598fe94c6dbdae3167609426d0db34a316b6aad7b668ea2d4f9"} 19:07:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:50 INFO - Copying logs to upload dir... 19:07:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.453098 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-24 19:07:50.214015) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 19:07:50.217538) ========= 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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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/176eb41a713b810b00314f82318372f39a3d4e6783f013590281f88644dbdc1af9d27458ab9d2bf1cf22c30c7d63a4fcf32e0697bf4fb888c93c12f2f17e3979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6261c7724c67f5661cad501e82e40675c9644af98751ea6ca6c51705cb4978851fc90e9a3c6e84b1b477faf443245a526a77405a2b3ecda04672a8d6203202e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08b470603c5ea16373eb980870aa543d5f5f9098eaaa94e3dab6b631bf3deb23fa5f2e5cba4f4ca5861e0e10d3d4bbafed90d376838254dd93ef4c1a37d7e696", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cc1ddd5d7402792c25bfd2c74deb866ae2869c93a2b5936f936eccec163e0fb2b8599684732598fe94c6dbdae3167609426d0db34a316b6aad7b668ea2d4f9"} build_url:https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011077 build_url: 'https://queue.taskcluster.net/v1/task/H2vM7vR6TFKFi2SlMk-drA/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/176eb41a713b810b00314f82318372f39a3d4e6783f013590281f88644dbdc1af9d27458ab9d2bf1cf22c30c7d63a4fcf32e0697bf4fb888c93c12f2f17e3979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6261c7724c67f5661cad501e82e40675c9644af98751ea6ca6c51705cb4978851fc90e9a3c6e84b1b477faf443245a526a77405a2b3ecda04672a8d6203202e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08b470603c5ea16373eb980870aa543d5f5f9098eaaa94e3dab6b631bf3deb23fa5f2e5cba4f4ca5861e0e10d3d4bbafed90d376838254dd93ef4c1a37d7e696", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cc1ddd5d7402792c25bfd2c74deb866ae2869c93a2b5936f936eccec163e0fb2b8599684732598fe94c6dbdae3167609426d0db34a316b6aad7b668ea2d4f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-24 19:07:50.252526) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 19:07:50.252858) ========= 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.lrM5EzAJDD/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.AVIwtaeeJZ/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005062 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-24 19:07:50.306074) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 19:07:50.306794) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-24 19:07:50.307516) ========= ========= Total master_lag: 0.21 =========