builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0120 starttime: 1490295515.7 results: success (0) buildid: 20170323103823 builduid: 92feb9ccaeea4fc3baf3127b3c7ec655 revision: 200182ef115692c4ed2909f1a8beae8a6f19d127 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.703878) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.704330) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.704608) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.730725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.731045) ========= 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.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-23 11:58:35-- 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% 20.5M=0.001s 2017-03-23 11:58:35 (20.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142117 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.897007) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.897330) ========= 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.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.028040 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:35.947178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 11:58:35.947534) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 200182ef115692c4ed2909f1a8beae8a6f19d127 --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 200182ef115692c4ed2909f1a8beae8a6f19d127 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-23 11:58:36,049 truncating revision to first 12 chars 2017-03-23 11:58:36,049 Setting DEBUG logging. 2017-03-23 11:58:36,049 attempt 1/10 2017-03-23 11:58:36,049 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/200182ef1156?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 11:58:37,714 unpacking tar archive at: mozilla-central-200182ef1156/testing/mozharness/ program finished with exit code 0 elapsedTime=1.945574 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 11:58:37.918448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:37.918839) ========= script_repo_revision: 200182ef115692c4ed2909f1a8beae8a6f19d127 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:37.919275) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:37.919542) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 11:58:37.939194) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-23 11:58:37.939570) ========= /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.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:58:38 INFO - MultiFileLogger online at 20170323 11:58:38 in /builds/slave/test 11:58:38 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 11:58:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:58:38 INFO - {'append_to_log': False, 11:58:38 INFO - 'base_work_dir': '/builds/slave/test', 11:58:38 INFO - 'blob_upload_branch': 'Firefox', 11:58:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:58:38 INFO - 'branch': 'Firefox', 11:58:38 INFO - 'buildbot_json_path': 'buildprops.json', 11:58:38 INFO - 'config_files': ('talos/mac_config.py',), 11:58:38 INFO - 'default_actions': ('clobber', 11:58:38 INFO - 'read-buildbot-config', 11:58:38 INFO - 'download-and-extract', 11:58:38 INFO - 'populate-webroot', 11:58:38 INFO - 'create-virtualenv', 11:58:38 INFO - 'install', 11:58:38 INFO - 'run-tests'), 11:58:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:58:38 INFO - 'download_minidump_stackwalk': True, 11:58:38 INFO - 'download_symbols': 'ondemand', 11:58:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:58:38 INFO - 'tooltool.py': '/tools/tooltool.py', 11:58:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:58:38 INFO - '/tools/misc-python/virtualenv.py')}, 11:58:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:58:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:58:38 INFO - 'gecko_profile': False, 11:58:38 INFO - 'gecko_profile_interval': 0, 11:58:38 INFO - 'installer_path': 'installer.exe', 11:58:38 INFO - 'log_level': 'info', 11:58:38 INFO - 'log_name': 'talos', 11:58:38 INFO - 'log_to_console': True, 11:58:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:58:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:58:38 INFO - 'opt_config_files': (), 11:58:38 INFO - 'pip_index': False, 11:58:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:58:38 INFO - 'cmd': ('bash', 11:58:38 INFO - '-c', 11:58:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:58:38 INFO - 'enabled': True, 11:58:38 INFO - 'halt_on_failure': False, 11:58:38 INFO - 'name': 'check_screen_resolution'},), 11:58:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:58:38 INFO - 'cmd': ('bash', 11:58:38 INFO - '-c', 11:58:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:58:38 INFO - 'enabled': True, 11:58:38 INFO - 'halt_on_failure': False, 11:58:38 INFO - 'name': 'check_screen_resolution'},), 11:58:38 INFO - 'run_cmd_checks_enabled': True, 11:58:38 INFO - 'suite': 'dromaeojs', 11:58:38 INFO - 'system_bits': '32', 11:58:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:58:38 INFO - 'title': 't-yosemite-r7-0120', 11:58:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:58:38 INFO - 'use_talos_json': True, 11:58:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:58:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:58:38 INFO - 'work_dir': 'build'} 11:58:38 INFO - [mozharness: 2017-03-23 18:58:38.157812Z] Running clobber step. 11:58:38 INFO - Running pre-action listener: _resource_record_pre_action 11:58:38 INFO - Running main action method: clobber 11:58:38 INFO - rmtree: /builds/slave/test/build 11:58:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:58:40 INFO - Running post-action listener: _resource_record_post_action 11:58:40 INFO - [mozharness: 2017-03-23 18:58:40.712833Z] Finished clobber step (success) 11:58:40 INFO - [mozharness: 2017-03-23 18:58:40.713036Z] Running read-buildbot-config step. 11:58:40 INFO - Running pre-action listener: _resource_record_pre_action 11:58:40 INFO - Running main action method: read_buildbot_config 11:58:40 INFO - Using buildbot properties: 11:58:40 INFO - { 11:58:40 INFO - "project": "", 11:58:40 INFO - "product": "firefox", 11:58:40 INFO - "script_repo_revision": "production", 11:58:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:58:40 INFO - "repository": "", 11:58:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:58:40 INFO - "buildid": "20170323103823", 11:58:40 INFO - "pgo_build": "False", 11:58:40 INFO - "basedir": "/builds/slave/test", 11:58:40 INFO - "buildnumber": 54, 11:58:40 INFO - "slavename": "t-yosemite-r7-0120", 11:58:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:58:40 INFO - "platform": "yosemite_r7", 11:58:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:58:40 INFO - "branch": "mozilla-central", 11:58:40 INFO - "repo_path": "mozilla-central", 11:58:40 INFO - "revision": "200182ef115692c4ed2909f1a8beae8a6f19d127", 11:58:40 INFO - "stage_platform": "macosx64", 11:58:40 INFO - "builduid": "92feb9ccaeea4fc3baf3127b3c7ec655", 11:58:40 INFO - "slavebuilddir": "test" 11:58:40 INFO - } 11:58:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:58:40 INFO - Running post-action listener: _resource_record_post_action 11:58:40 INFO - [mozharness: 2017-03-23 18:58:40.716436Z] Finished read-buildbot-config step (success) 11:58:40 INFO - [mozharness: 2017-03-23 18:58:40.716570Z] Running download-and-extract step. 11:58:40 INFO - Running pre-action listener: _resource_record_pre_action 11:58:40 INFO - Running main action method: download_and_extract 11:58:40 INFO - mkdir: /builds/slave/test/build/tests 11:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:40 INFO - trying https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:58:40 INFO - Downloading https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/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 11:58:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/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 11:58:42 INFO - Downloaded 1325 bytes. 11:58:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:58:42 INFO - Using the following test package requirements: 11:58:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:58:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:58:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:58:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:58:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'jsshell-mac.zip'], 11:58:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:58:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:58:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:58:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:58:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:58:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:58:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:58:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:58:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:58:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:58:42 INFO - Fetch https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:58:43 INFO - Expected file size: 52412762 11:58:43 INFO - Obtained file size: 52412762 11:59:00 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 11:59:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:59:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:59:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:59:02 INFO - Expected file size: 52412762 11:59:02 INFO - Obtained file size: 52412762 11:59:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:59:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:59:17 INFO - Fetch https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:59:19 INFO - Expected file size: 14404171 11:59:19 INFO - Obtained file size: 14404171 11:59: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')]} 11:59:19 INFO - trying https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:59:19 INFO - Downloading https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/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 11:59:21 INFO - Downloaded 75448344 bytes. 11:59:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:59:21 INFO - mkdir: /builds/slave/test/properties 11:59:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:59:21 INFO - Writing to file /builds/slave/test/properties/build_url 11:59:21 INFO - Contents: 11:59:21 INFO - build_url:https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:59:22 INFO - Running post-action listener: _resource_record_post_action 11:59:22 INFO - Running post-action listener: set_extra_try_arguments 11:59:22 INFO - [mozharness: 2017-03-23 18:59:22.454018Z] Finished download-and-extract step (success) 11:59:22 INFO - [mozharness: 2017-03-23 18:59:22.454135Z] Running populate-webroot step. 11:59:22 INFO - Running pre-action listener: _resource_record_pre_action 11:59:22 INFO - Running main action method: populate_webroot 11:59:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:59:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:59:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:59:22 INFO - '--tppagecycles', 11:59:22 INFO - '7'], 11:59:22 INFO - 'tests': ['tsvgm']}}, 11:59:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:59:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:59:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:59:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:59:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:59:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:59:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['damp', 'tps']}, 11:59:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'tests': ['damp', 'tps']}, 11:59:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['dromaeo_dom']}, 11:59:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:59:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['basic_compositor_video']}, 11:59:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:59:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'other-e10s': {'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['a11yr', 11:59:22 INFO - 'ts_paint', 11:59:22 INFO - 'tpaint', 11:59:22 INFO - 'sessionrestore', 11:59:22 INFO - 'sessionrestore_no_auto_restore', 11:59:22 INFO - 'tabpaint']}, 11:59:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['tsvgx', 11:59:22 INFO - 'tsvgr_opacity', 11:59:22 INFO - 'tart', 11:59:22 INFO - 'tscrollx', 11:59:22 INFO - 'cart', 11:59:22 INFO - 'tsvg_static']}, 11:59:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:59:22 INFO - 'tsvgr_opacity', 11:59:22 INFO - 'tart', 11:59:22 INFO - 'tscrollx', 11:59:22 INFO - 'cart', 11:59:22 INFO - 'tsvg_static']}, 11:59:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'talos_options': ['--disable-e10s'], 11:59:22 INFO - 'tests': ['tp5o']}, 11:59:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:59:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'talos_options': ['--disable-e10s', 11:59:22 INFO - '--xperf_path', 11:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:59:22 INFO - 'tests': ['tp5n']}, 11:59:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:59:22 INFO - 'talos_options': ['--xperf_path', 11:59:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:59:22 INFO - 'tests': ['tp5n']}}, 11:59:22 INFO - 'talos.zip': {'path': '', 11:59:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:59:22 INFO - Running post-action listener: _resource_record_post_action 11:59:22 INFO - [mozharness: 2017-03-23 18:59:22.472554Z] Finished populate-webroot step (success) 11:59:22 INFO - [mozharness: 2017-03-23 18:59:22.472657Z] Running create-virtualenv step. 11:59:22 INFO - Running pre-action listener: _resource_record_pre_action 11:59:22 INFO - Running main action method: create_virtualenv 11:59:22 INFO - Creating virtualenv /builds/slave/test/build/venv 11:59:22 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 11:59:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:59:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:59:22 INFO - Using real prefix '/tools/python27' 11:59:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:59:23 INFO - Installing distribute.............................................................................................................................................................................................done. 11:59:26 INFO - Installing pip.................done. 11:59:26 INFO - Return code: 0 11:59:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:59:26 INFO - Reading from file tmpfile_stdout 11:59:26 INFO - Output received: 11:59:26 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) 11:59:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:26 INFO - 'HOME': '/Users/cltbld', 11:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:26 INFO - 'LOGNAME': 'cltbld', 11:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:26 INFO - 'MOZ_NO_REMOTE': '1', 11:59:26 INFO - 'NO_EM_RESTART': '1', 11:59:26 INFO - 'PAGER': '/bin/cat', 11:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:26 INFO - 'PWD': '/builds/slave/test', 11:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:26 INFO - 'SHELL': '/bin/bash', 11:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:26 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:26 INFO - 'USER': 'cltbld', 11:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:26 INFO - 'XPC_FLAGS': '0x0', 11:59:26 INFO - 'XPC_SERVICE_NAME': '0', 11:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:26 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 11:59:26 INFO - Cleaning up... 11:59:26 INFO - Return code: 0 11:59:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:26 INFO - 'HOME': '/Users/cltbld', 11:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:26 INFO - 'LOGNAME': 'cltbld', 11:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:26 INFO - 'MOZ_NO_REMOTE': '1', 11:59:26 INFO - 'NO_EM_RESTART': '1', 11:59:26 INFO - 'PAGER': '/bin/cat', 11:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:26 INFO - 'PWD': '/builds/slave/test', 11:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:26 INFO - 'SHELL': '/bin/bash', 11:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:26 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:26 INFO - 'USER': 'cltbld', 11:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:26 INFO - 'XPC_FLAGS': '0x0', 11:59:26 INFO - 'XPC_SERVICE_NAME': '0', 11:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:27 INFO - Downloading/unpacking psutil>=3.1.1 11:59: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 11:59: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 11:59: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 11:59: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 11:59: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 11:59: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 11:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:59:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:59:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:59:30 INFO - Installing collected packages: psutil 11:59:30 INFO - Running setup.py install for psutil 11:59:30 INFO - building 'psutil._psutil_osx' extension 11:59:30 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 11:59:31 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 11:59:31 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 11:59:31 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 11:59:31 INFO - building 'psutil._psutil_posix' extension 11:59:31 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 11:59:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:59:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:59:31 INFO - ^ 11:59:31 INFO - 1 warning generated. 11:59:31 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 11:59:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:59:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:59:31 INFO - Successfully installed psutil 11:59:31 INFO - Cleaning up... 11:59:31 INFO - Return code: 0 11:59:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:59:31 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')]} 11:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:32 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:32 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 11:59:32 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 11:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:32 INFO - 'HOME': '/Users/cltbld', 11:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:32 INFO - 'LOGNAME': 'cltbld', 11:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:32 INFO - 'MOZ_NO_REMOTE': '1', 11:59:32 INFO - 'NO_EM_RESTART': '1', 11:59:32 INFO - 'PAGER': '/bin/cat', 11:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:32 INFO - 'PWD': '/builds/slave/test', 11:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:32 INFO - 'SHELL': '/bin/bash', 11:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:32 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:32 INFO - 'USER': 'cltbld', 11:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:32 INFO - 'XPC_FLAGS': '0x0', 11:59:32 INFO - 'XPC_SERVICE_NAME': '0', 11:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:59:33 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 11:59:33 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 11:59:33 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 11:59:33 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 11:59:33 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 11:59:33 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 11:59:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:59:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:59:36 INFO - Installing collected packages: mozsystemmonitor 11:59:36 INFO - Running setup.py install for mozsystemmonitor 11:59:36 INFO - Successfully installed mozsystemmonitor 11:59:36 INFO - Cleaning up... 11:59:36 INFO - Return code: 0 11:59:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:36 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:36 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 11:59:36 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 11:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:36 INFO - 'HOME': '/Users/cltbld', 11:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:36 INFO - 'LOGNAME': 'cltbld', 11:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:36 INFO - 'MOZ_NO_REMOTE': '1', 11:59:36 INFO - 'NO_EM_RESTART': '1', 11:59:36 INFO - 'PAGER': '/bin/cat', 11:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:36 INFO - 'PWD': '/builds/slave/test', 11:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:36 INFO - 'SHELL': '/bin/bash', 11:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:36 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:36 INFO - 'USER': 'cltbld', 11:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:36 INFO - 'XPC_FLAGS': '0x0', 11:59:36 INFO - 'XPC_SERVICE_NAME': '0', 11:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:36 INFO - Downloading/unpacking jsonschema==2.5.1 11:59: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 11:59: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 11:59: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 11:59: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 11:59: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 11:59: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 11:59:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:59:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:59:39 INFO - warning: no files found matching '*.txt' 11:59:39 INFO - no previously-included directories found matching 'build' 11:59:39 INFO - no previously-included directories found matching 'dist' 11:59:39 INFO - no previously-included directories found matching '.git*' 11:59:39 INFO - Installing collected packages: jsonschema, functools32 11:59:39 INFO - Running setup.py install for functools32 11:59:39 INFO - warning: no files found matching '*.txt' 11:59:39 INFO - no previously-included directories found matching 'build' 11:59:39 INFO - no previously-included directories found matching 'dist' 11:59:39 INFO - no previously-included directories found matching '.git*' 11:59:39 INFO - Successfully installed jsonschema functools32 11:59:39 INFO - Cleaning up... 11:59:39 INFO - Return code: 0 11:59:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:59: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')]} 11:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59: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')]} 11:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59: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', '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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:59: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 functools32==3.2.3-2 11:59:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:39 INFO - 'HOME': '/Users/cltbld', 11:59:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:39 INFO - 'LOGNAME': 'cltbld', 11:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:39 INFO - 'MOZ_NO_REMOTE': '1', 11:59:39 INFO - 'NO_EM_RESTART': '1', 11:59:39 INFO - 'PAGER': '/bin/cat', 11:59:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:39 INFO - 'PWD': '/builds/slave/test', 11:59:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:39 INFO - 'SHELL': '/bin/bash', 11:59:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:39 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:39 INFO - 'USER': 'cltbld', 11:59:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:39 INFO - 'XPC_FLAGS': '0x0', 11:59:39 INFO - 'XPC_SERVICE_NAME': '0', 11:59:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:59:40 INFO - Cleaning up... 11:59:40 INFO - Return code: 0 11:59:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:40 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:40 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 11:59:40 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 11:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:40 INFO - 'HOME': '/Users/cltbld', 11:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:40 INFO - 'LOGNAME': 'cltbld', 11:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:40 INFO - 'MOZ_NO_REMOTE': '1', 11:59:40 INFO - 'NO_EM_RESTART': '1', 11:59:40 INFO - 'PAGER': '/bin/cat', 11:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:40 INFO - 'PWD': '/builds/slave/test', 11:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:40 INFO - 'SHELL': '/bin/bash', 11:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:40 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:40 INFO - 'USER': 'cltbld', 11:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:40 INFO - 'XPC_FLAGS': '0x0', 11:59:40 INFO - 'XPC_SERVICE_NAME': '0', 11:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:40 INFO - Downloading/unpacking blobuploader==1.2.4 11:59:40 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 11:59:40 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 11:59:40 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 11:59:40 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 11:59:40 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 11:59:40 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 11:59:43 INFO - Downloading blobuploader-1.2.4.tar.gz 11:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:59:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:59:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:44 INFO - Downloading docopt-0.6.1.tar.gz 11:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:59:44 INFO - Installing collected packages: blobuploader, requests, docopt 11:59:44 INFO - Running setup.py install for blobuploader 11:59:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:59:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:59:44 INFO - Running setup.py install for requests 11:59:45 INFO - Running setup.py install for docopt 11:59:45 INFO - Successfully installed blobuploader requests docopt 11:59:45 INFO - Cleaning up... 11:59:45 INFO - Return code: 0 11:59:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:45 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')]} 11:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:45 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')]} 11:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:45 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:45 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 11:59:45 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 11:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:45 INFO - 'HOME': '/Users/cltbld', 11:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:45 INFO - 'LOGNAME': 'cltbld', 11:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:45 INFO - 'MOZ_NO_REMOTE': '1', 11:59:45 INFO - 'NO_EM_RESTART': '1', 11:59:45 INFO - 'PAGER': '/bin/cat', 11:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:45 INFO - 'PWD': '/builds/slave/test', 11:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:45 INFO - 'SHELL': '/bin/bash', 11:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:45 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:45 INFO - 'USER': 'cltbld', 11:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:45 INFO - 'XPC_FLAGS': '0x0', 11:59:45 INFO - 'XPC_SERVICE_NAME': '0', 11:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-v1eEQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2T_3Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Re1aKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UyayOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-RVkDJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-gA8pD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-eA0HhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-j33H2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-o9MCBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oJNkMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-WXBGFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-52BQTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-mzfadB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-tiE6KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:47 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-A2uTya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:47 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-DfICJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:47 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-jL5JGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:59:47 INFO - Running setup.py install for manifestparser 11:59:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:59:47 INFO - Running setup.py install for mozcrash 11:59:47 INFO - Running setup.py install for mozdebug 11:59:47 INFO - Running setup.py install for mozdevice 11:59:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:59:47 INFO - Running setup.py install for mozfile 11:59:48 INFO - Running setup.py install for mozhttpd 11:59:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Running setup.py install for mozinfo 11:59:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Running setup.py install for mozInstall 11:59:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Running setup.py install for mozleak 11:59:48 INFO - Running setup.py install for mozlog 11:59:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Running setup.py install for moznetwork 11:59:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:59:48 INFO - Running setup.py install for mozprocess 11:59:49 INFO - Running setup.py install for mozprofile 11:59:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:59:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:59:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:59:49 INFO - Running setup.py install for mozrunner 11:59:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:59:49 INFO - Running setup.py install for mozscreenshot 11:59:49 INFO - Running setup.py install for moztest 11:59:49 INFO - Running setup.py install for mozversion 11:59:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:59:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:59:49 INFO - Cleaning up... 11:59:49 INFO - Return code: 0 11:59:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:49 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:49 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 11:59:49 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 11:59:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:49 INFO - 'HOME': '/Users/cltbld', 11:59:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:49 INFO - 'LOGNAME': 'cltbld', 11:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:49 INFO - 'MOZ_NO_REMOTE': '1', 11:59:49 INFO - 'NO_EM_RESTART': '1', 11:59:49 INFO - 'PAGER': '/bin/cat', 11:59:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:49 INFO - 'PWD': '/builds/slave/test', 11:59:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:49 INFO - 'SHELL': '/bin/bash', 11:59:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:49 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:49 INFO - 'USER': 'cltbld', 11:59:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:49 INFO - 'XPC_FLAGS': '0x0', 11:59:49 INFO - 'XPC_SERVICE_NAME': '0', 11:59:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-OJzwGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-4CIQvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-jbBNp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-n39lrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-0N49Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ScK0Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-q5skU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:59:50 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)) 11:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:59:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-lQoH8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-5lhzNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-B_7H76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-p82j81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kovpiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-zKHiPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-FXslxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oJ5_Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ka_1Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:59:51 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)) 11:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:59:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Gyt0Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:59:52 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)) 11:59:52 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)) 11:59:52 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)) 11:59:52 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)) 11:59:52 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)) 11:59:52 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)) 11:59:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:59:55 INFO - Downloading blessings-1.6.tar.gz 11:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:59:55 INFO - Installing collected packages: blessings 11:59:55 INFO - Running setup.py install for blessings 11:59:55 INFO - Successfully installed blessings 11:59:55 INFO - Cleaning up... 11:59:55 INFO - Return code: 0 11:59:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:59:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:55 INFO - Reading from file tmpfile_stdout 11:59:55 INFO - Current package versions: 11:59:55 INFO - blessings == 1.6 11:59:55 INFO - blobuploader == 1.2.4 11:59:55 INFO - docopt == 0.6.1 11:59:55 INFO - functools32 == 3.2.3-2 11:59:55 INFO - jsonschema == 2.5.1 11:59:55 INFO - manifestparser == 1.1 11:59:55 INFO - mozInstall == 1.12 11:59:55 INFO - mozcrash == 1.0 11:59:55 INFO - mozdebug == 0.1 11:59:55 INFO - mozdevice == 0.49 11:59:55 INFO - mozfile == 1.2 11:59:55 INFO - mozhttpd == 0.7 11:59:55 INFO - mozinfo == 0.9 11:59:55 INFO - mozleak == 0.1 11:59:55 INFO - mozlog == 3.4 11:59:55 INFO - moznetwork == 0.27 11:59:55 INFO - mozprocess == 0.25 11:59:55 INFO - mozprofile == 0.28 11:59:55 INFO - mozrunner == 6.13 11:59:55 INFO - mozscreenshot == 0.1 11:59:55 INFO - mozsystemmonitor == 0.3 11:59:55 INFO - moztest == 0.8 11:59:55 INFO - mozversion == 1.4 11:59:55 INFO - psutil == 3.1.1 11:59:55 INFO - requests == 1.2.3 11:59:55 INFO - wsgiref == 0.1.2 11:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:59:55 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')]} 11:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:55 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')]} 11:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:55 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 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:55 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 11:59:55 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 11:59:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:55 INFO - 'HOME': '/Users/cltbld', 11:59:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:55 INFO - 'LOGNAME': 'cltbld', 11:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:55 INFO - 'MOZ_NO_REMOTE': '1', 11:59:55 INFO - 'NO_EM_RESTART': '1', 11:59:55 INFO - 'PAGER': '/bin/cat', 11:59:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:55 INFO - 'PWD': '/builds/slave/test', 11:59:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:55 INFO - 'SHELL': '/bin/bash', 11:59:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:55 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:55 INFO - 'USER': 'cltbld', 11:59:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:55 INFO - 'XPC_FLAGS': '0x0', 11:59:55 INFO - 'XPC_SERVICE_NAME': '0', 11:59:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 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)) 11:59:56 INFO - Cleaning up... 11:59:56 INFO - Return code: 0 11:59:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:59:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:59:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:59:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa130d75500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, '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.a3HBK113wO/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.XPync5f5Az/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 11:59:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 11:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:59:56 INFO - 'HOME': '/Users/cltbld', 11:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:59:56 INFO - 'LOGNAME': 'cltbld', 11:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:59:56 INFO - 'MOZ_NO_REMOTE': '1', 11:59:56 INFO - 'NO_EM_RESTART': '1', 11:59:56 INFO - 'PAGER': '/bin/cat', 11:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:59:56 INFO - 'PWD': '/builds/slave/test', 11:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:59:56 INFO - 'SHELL': '/bin/bash', 11:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 11:59:56 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 11:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:59:56 INFO - 'USER': 'cltbld', 11:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:59:56 INFO - 'XPC_FLAGS': '0x0', 11:59:56 INFO - 'XPC_SERVICE_NAME': '0', 11:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:59:56 INFO - Cleaning up... 11:59:56 INFO - Return code: 0 11:59:56 INFO - Running post-action listener: _resource_record_post_action 11:59:56 INFO - Running post-action listener: _start_resource_monitoring 11:59:56 INFO - Starting resource monitoring. 11:59:56 INFO - [mozharness: 2017-03-23 18:59:56.587031Z] Finished create-virtualenv step (success) 11:59:56 INFO - [mozharness: 2017-03-23 18:59:56.588063Z] Running install step. 11:59:56 INFO - Running pre-action listener: _resource_record_pre_action 11:59:56 INFO - Running main action method: install 11:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:59:56 INFO - Reading from file tmpfile_stdout 11:59:56 INFO - Detecting whether we're running mozinstall >=1.0... 11:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:59:56 INFO - Reading from file tmpfile_stdout 11:59:56 INFO - Output received: 11:59:56 INFO - Usage: mozinstall [options] installer 11:59:56 INFO - Options: 11:59:56 INFO - -h, --help show this help message and exit 11:59:56 INFO - -d DEST, --destination=DEST 11:59:56 INFO - Directory to install application into. [default: 11:59:56 INFO - "/builds/slave/test"] 11:59:56 INFO - --app=APP Application being installed. [default: firefox] 11:59:56 INFO - mkdir: /builds/slave/test/build/application 11:59:56 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'] 11:59:56 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 12:00:17 INFO - Reading from file tmpfile_stdout 12:00:17 INFO - Output received: 12:00:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:00:17 INFO - Running post-action listener: _resource_record_post_action 12:00:17 INFO - [mozharness: 2017-03-23 19:00:17.263972Z] Finished install step (success) 12:00:17 INFO - [mozharness: 2017-03-23 19:00:17.264180Z] Running run-tests step. 12:00:17 INFO - Running pre-action listener: _resource_record_pre_action 12:00:17 INFO - Running main action method: run_tests 12:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:00:17 INFO - 2017-03-23 12:00:17.278 screenresolution[1627:7269] starting screenresolution argv=screenresolution get 12:00:17 INFO - 2017-03-23 12:00:17.281 screenresolution[1627:7269] Display 0: 1600x1200x32@60 12:00:17 INFO - 2017-03-23 12:00:17.287 screenresolution[1628:7272] starting screenresolution argv=screenresolution list 12:00:17 INFO - Available Modes on Display 0 12:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:00:17 INFO - Intel Iris: 12:00:17 INFO - Chipset Model: Intel Iris 12:00:17 INFO - Type: GPU 12:00:17 INFO - Bus: Built-In 12:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 12:00:17 INFO - Vendor: Intel (0x8086) 12:00:17 INFO - Device ID: 0x0a2e 12:00:17 INFO - Revision ID: 0x0009 12:00:17 INFO - Displays: 12:00:17 INFO - Display: 12:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:00:17 INFO - Display Serial Number: 1600x1200 60 12:00:17 INFO - Main Display: Yes 12:00:17 INFO - Mirror: Off 12:00:17 INFO - Online: Yes 12:00:17 INFO - Rotation: Supported 12:00:17 INFO - Return code: 0 12:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:00:17 INFO - Python 2.7.3 12:00:17 INFO - Return code: 0 12:00:17 INFO - grabbing minidump binary from tooltool 12:00:17 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')]} 12:00:17 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 0x10aa6cd30>, '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 0x10a993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa61be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:00:17 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 12:00:17 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 12:00:17 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 12:00:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:00:17 INFO - Return code: 0 12:00:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:00:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:00:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:00:17 INFO - ENV: RUST_BACKTRACE is now 1 12:00:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:00:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:00:17 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/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 12:00:17 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/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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 12:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.a3HBK113wO/Render', 12:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:17 INFO - 'HOME': '/Users/cltbld', 12:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:17 INFO - 'LOGNAME': 'cltbld', 12:00:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:00:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:17 INFO - 'MOZ_NO_REMOTE': '1', 12:00:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:00:17 INFO - 'NO_EM_RESTART': '1', 12:00:17 INFO - 'PAGER': '/bin/cat', 12:00:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:17 INFO - 'PWD': '/builds/slave/test', 12:00:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:17 INFO - 'RUST_BACKTRACE': '1', 12:00:17 INFO - 'SHELL': '/bin/bash', 12:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XPync5f5Az/Listeners', 12:00:17 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 12:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:17 INFO - 'USER': 'cltbld', 12:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:17 INFO - 'XPC_FLAGS': '0x0', 12:00:17 INFO - 'XPC_SERVICE_NAME': '0', 12:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:17 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/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 12:00:17 INFO - mozversion application_buildid: 20170323103823 12:00:17 INFO - mozversion application_changeset: 200182ef115692c4ed2909f1a8beae8a6f19d127 12:00:17 INFO - mozversion application_display_name: Nightly 12:00:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:00:17 INFO - mozversion application_name: Firefox 12:00:17 INFO - mozversion application_remotingname: firefox 12:00:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:00:17 INFO - mozversion application_vendor: Mozilla 12:00:17 INFO - mozversion application_version: 55.0a1 12:00:17 INFO - mozversion platform_buildid: 20170323103823 12:00:17 INFO - mozversion platform_changeset: 200182ef115692c4ed2909f1a8beae8a6f19d127 12:00:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:00:17 INFO - mozversion platform_version: 55.0a1 12:00:17 INFO - using testdate: 1490295617 12:00:17 INFO - actual date: 1490295617 12:00:17 INFO - starting webserver on 'localhost:49240' 12:00:17 INFO - SUITE-START | Running 2 tests 12:00:17 INFO - TEST-START | dromaeo_css 12:00:17 INFO - operating with platform_type : mac_ 12:00:17 INFO - Initialising browser for dromaeo_css test... 12:00:17 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpcmzBZi/profile http://localhost:49240/getInfo.html) 12:00:20 INFO - PID 1638 | __metrics Screen width/height:1600/1200 12:00:20 INFO - PID 1638 | colorDepth:24 12:00:20 INFO - PID 1638 | Browser inner width/height: 1024/689 12:00:20 INFO - PID 1638 | __metrics 12:00:20 INFO - PID 1638 | 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" 12:00:20 INFO - PID 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:00:21 INFO - TEST-INFO | 1638: exit 0 12:00:21 INFO - Browser initialized. 12:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 12:00:21 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpcmzBZi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:00:33 INFO - PID 1640 | RSS: Main: 234000384 12:00:33 INFO - PID 1640 | 12:03:38 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 12:03:40 INFO - PID 1640 | RSS: Main: 374595584 12:03:40 INFO - PID 1640 | 12:05:23 INFO - PID 1640 | ************************* 12:05:23 INFO - PID 1640 | A coding exception was thrown and uncaught in a Task. 12:05:23 INFO - PID 1640 | 12:05:23 ERROR - PID 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:05:23 INFO - PID 1640 | Full stack: 12:05:23 INFO - PID 1640 | ************************* 12:06:43 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 12:06:43 INFO - PID 1640 | RSS: Main: 368283648 12:06:43 INFO - PID 1640 | 12:09:45 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 12:09:46 INFO - PID 1640 | RSS: Main: 334458880 12:09:46 INFO - PID 1640 | 12:12:49 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 12:12:49 INFO - PID 1640 | RSS: Main: 332664832 12:12:49 INFO - PID 1640 | 12:15:53 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 12:15:54 INFO - PID 1640 | RSS: Main: 338042880 12:15:54 INFO - PID 1640 | 12:18:57 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 12:18:58 INFO - PID 1640 | RSS: Main: 426655744 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | __start_tp_report 12:18:58 INFO - PID 1640 | _x_x_mozilla_page_load 12:18:58 INFO - PID 1640 | _x_x_mozilla_page_load_details 12:18:58 INFO - PID 1640 | |i|pagename|runs| 12:18:58 INFO - PID 1640 | |0;dojo.html;2792.0786140573614;3129.467990441625;3340.248444100077;3499.405101132807;3569.910752231194;5983.880322393549;6118.449339559439;6249.5000399968;6252.562320637546;6255.6559389233635;6017.097435384694;6098.39016098391;6104.572679912408;6108.175396321495;6111.297200821896;5392.137258038714;5424.294841670578;5444.101723215665;5449.64577302474;5560.972195139018;5276.577873770108;5608.327000759915;5608.495235428811;5659.490645841874;5664.688442135681;5615.634983726065;5627.71861406928;5632.831015069554;5638.069718496443;5638.956792993309;792.1913117154237;5451.48210919962;5455.236266922634;5585.916211256835;5618.971905140468;5439.184122381635;5503.587230957694;5511.448855114497;5543.196236545718;5547.55619550435;6126.785562505335;6180.567360284782;6189.81430557084;6197.876042479125;6209.348018458042;6316.62100273985;6387.93612063878;6570.802875913682;6582.506312026617;6594.604323740591;6461.19235095613;6536.411722944958;6626.2048554173325;6665.266820649725;6706.664666766642;6257.906131408033;6298.622082675054;6302.054691796176;6304.779332723333;6316.526260530432;6718.025886246564;6729.091572637729;6783.084283621746;6788.898166527506;6794.932050679528;6308.653024083719;6354.205724284465;6356.8410789729705;6358.872822543523;6377.71300291488;6416.711247993851;6423.13287706163;6434.581752186093;6434.8391290218115;6444.903326450107;6476.6114033158165;6486.383793539606;6561.737530498822;6574.704138313788;6583.374579414948;6481.740730370732;6577.934220657828;6607.174103237096;6607.603543787388;6619.040239165391;5140.665856719302;5308.495692909168;5324.068288049653;5341.385740639798;5351.25082488452;4663.160631086369;4666.906661866744;4712.24604063355;4723.409573803275;4733.83431579893;6216.937830621727;6248.4688801451475;6256.186695729526;6269.811905642838;6272.341404152589;6650;6658.067870498134;6659.433948114368;6660.500462465335;6662.5003124765835;6092.421219984094;6112.077967281658;6124.564025909317;6172.074188871705;6258.468030217391;5831.650100993954;5850.3272123705465;5850.47345738877;5866.413358664099;5881.2942446906645;6747.493937954771;6790;6794.898076528757;6796.796096117125;6797.762078327235;6381.521385895983;6451.967740161269;6623.437007854193;6629.270780214269;6682.565633234019;6567.835804104936;6593.769218077537;6595.637239951657;6599.142111525471;6599.670016499252;6384.648844313608;6405.871882562229;6415.743370265323;6486.254080780771;6540.051692504465;6567.507436942153;6609.074729537772;6621.039949207434;6625.536212465274;6644.235912869888;6399.8720025598295;6421.133147025092;6424.068510066108;6429.807105786834;6436.67816609177;6113.327533971348;6330.841728956813;6388.425041746265;6410.1025856381075;6413.134226879311;6226.906596401148;6273.121762953281;6339.778107766206;6343.5876668016435;6346.936530634634;6440.484761218998;6443.291237963914;6455.4512866405;6462.903056454251;6471.288158302489;6325.905111423241;6333.018382150774;6344.619322840645;6348.936510634834;6350.142730731477 12:18:58 INFO - PID 1640 | |1;ext.html;754.7132089805873;796.9242921922418;817.7914631768898;1120.518177183811;1141.0301243942654;27727;28135.437291254162;28146.57780133297;28150.859245703767;28171.15486535402;28002.439951200962;36917.630823691754;37388.62611373892;37454.8127259364;37499;8555.61499732512;8711;8721.825563488726;8733.606987685569;8743.475391476532;8527.232549070583;8685.34859885508;8687.087855775148;8699;8800.515971621558;6122.846928826771;6230.844228894269;8378.539180345078;8550.486970781742;8551.957240213822;8543.914560854406;8603.913960860375;8619.698310559132;8623.353248506324;8626.137386261387;8391.664333426656;8489.151084891524;8491.320694344438;8499.36254780894;8530.360222983301;25926;26265.606015909776;36639;37045.44431833525;37495;2871.124307086338;3017.6982301769867;3020.7528096569044;3033.930940907702;3044.1780719205913;22187.889060554593;28453.00414485483;28465;28884;29241.122766317043;2756.1731480555754;2772.5702516110036;2820.1257610140924;2832.6317578714843;2846.644169478815;246342;247288;247443;247649;248229;47046.29430558544;47791.283130753065;48551.75724121357;48558.028839423016;48734.781630459525;50154.74613134628;50226.74886625543;50262.49737502651;50269;50321.741956451384;14293.499727509487;14386.640333991734;14415.279236038155;14416;14429.711405771826;11602.477888495037;11627.53489860413;11628.360440175871;11648.242864213798;11658.242214256046;11340.149488788209;13303.933480332536;13671.794923076162;13685.79471307931;13775.655608609864;4651.845121478023;4860.73265970368;4870.586000190024;5631.1271752878365;5745.712714364265;930.1767935377104;931.4644079654225;943.9858402123974;981.5533932060895;985.975350616226;23524.88237558801;24958.875205623855;24982.750172498407;25007.62488562673;25042.499150016898;951.5480146930153;1044.102072217892;1089.1069323155095;1091;1096.9670909872716;12028.45871935765;12675.985921126288;12688;12688.87311126877;12714.491420343267;488621;493486;493543;494678;494705;56837.715811421505;57285.85428291411;57303.71348143233;57308.42691573114;57350.71324643434;1429.9714005720036;1468.889833262531;1507.6758496923214;1516.8028156339606;1519.6352875309847;23978.77082687807;24267.029318827048;24283.90722417531;24293.87853060794;24296.028158873447;39411;39469.40795888007;39504.01239968909;39522;39533.40699889446;420624;423991;424052;424465.8776706097;427409;27300;34259.486107707904;34748;34933.951981440594;35418;31551;35849.1037724059;43891.34162987622;44831.32753008773;45306.77346613246;28280.575791363557;28328.57507137436;29398.265043374086;29489;30290.09129726112;27977.44045119127;29161.56257656179;29350;29971.700282997765;30309.848450758487;35350.46974295336;36319.818400907825;36444.54221831203;36446.817765911;36458;29480.968166114082;29549.852250738608;30533.694663053084;30636.540451892793;30732.84633576818;20564.69152962677;22421.215257465912;22626.22023720341;23845.04619815188;24614.753852461246 12:18:58 INFO - PID 1640 | |2;jquery.html;426.5859364249346;431.1420273655423;434.25091716788546;440.5704438172782;454.53867308524343;21079.894600526994;21462;21817.454563635896;22573.887130564366;22877.885610571924;21924.780752192473;22218.00018999145;22735.545289094207;23041.193558225466;23340;5477.931803298358;5501.257330260403;6011.278646562397;6082.604630699001;6311.0217916223055;6070.392960703938;6226.066090086478;6246.750129994794;6248.593841400318;6255.4370106690385;5917.704114794265;6128.3258841527395;6202.72087756052;6217.471514921237;6222.03558448439;5448.155535891943;5942.821715348547;5989.460948514655;6136.7545298188015;6172.876542469148;5388.703621300827;5413.675179489204;5496.862578435531;5498.092814685572;5498.780048798043;24363.26910192697;28011.019614313605;28030.579541306895;28112;28118.2970425741;2026.6048120616485;2043.3154893110825;2053.2813515269686;2100.8004239597212;2133.4453042208984;16094.275757590936;16464;16584.75122873158;16750.41373551932;17403;1622.1459040568502;1676.639522502668;1689.7718807960891;1697.1429428138833;1701.2344444999799;121094.3945280268;150561;151620;155908;157353.21323393425;11831.940840295916;12025.398730063462;12220.633380998584;12269.386530673431;12388.318642474751;13440.93279533596;16320.102394368187;16333.509994700178;16339.264733086802;16356.93679910802;8516;8845.336599754915;8863.423877447945;8990.55047247635;8997.100289970951;7970;8346.582670866432;8403.243708066304;8532.061473237938;8603.526806025604;6386.425221730066;7671.232876712395;7706.73026444083;7716.691332346755;7744.49660772048;1892.2714754819494;1920.6830872906094;2062;2064.308456667003;2071.3475255294534;898.5058217980215;901.4636291406603;903.8553831386946;954.9140577347933;965.6234068713208;20571.38285851427;21149.471263218544;21172.78827211739;21241.36275911725;21298.680519792215;940.2149205413419;940.9012053734259;974.7222041718218;983.0562659846485;983.149575617086;10867;11092.057175140042;11163.441827908571;11187.4965626547;11192.720181995515;475125;692110;704728.476357615;710864;710991;151211;155121.73852724553;157182;157999.21000394924;159727;936.681528280388;1152.4065106470175;1177.8763229860742;1178.0457829158408;1228.10962052513;20950.952452377624;21101.472463188544;21401.464963375416;21619.567608648053;21963;58636;58710.70644646749;59077.40922590719;59934.70032649809;62586.68706656437;85198;87275.8181045479;87364.6317684126;88439;90510.54744726498;8486.957565212135;8489.490630562186;8517.74446766598;8764.78088047804;8920.107989201288;12034.887182235187;13541.526046588322;13841.031127820956;13860.930695346458;14072.718545629235;11060.557577697124;11793.997510211384;11834;11876.406179690808;11932.701682458008;10644.89355106439;10695.57217711314;10927.726806829574;11069.667909962714;11287.548498060314;10254.6410875619;11522.596709115141;11744;11882.049436044923;12227.877721222674;6802.01370801241;8659.393842430969;8862.64549418016;8880.28957683371;9152.17630413266;9755.707328779865;11559.768804623693;11571;11992.220505667105;12015.579454719043 12:18:58 INFO - PID 1640 | |3;mootools.html;1182.9645110646672;1204.7831390349738;1461.9561413157603;1473.197107576371;1476.6456050547865;8830.249428798552;9201.079892010795;10636.414997175152;10944.45277736114;10966.396848173366;10809.83785243222;10983.890161098387;10995.890041099587;11029;11117.055050320732;2454.4600187958663;2497.4880149569385;2499.3126890105277;2503.674522312097;2510.134003768697;2079.241077006897;2150.569886022794;2152.7201463809683;2156.02978659603;2158.147531724968;2344.6834677318516;2421.3462365161395;2422.6244932035474;2433;2433.452473193535;2362.102401087593;2380.0241900820665;2388.522295540899;2388.9727417210593;2395.137750409851;2399.1483023526744;2401.579723548372;2423.127674037345;2439.536488067279;2442.755724427561;9069;10998.560057597686;11097.44512774358;11112.944435277852;11133.276337038147;6149.938500615026;6386.776462823826;6394.552381333308;6399.36006399361;6411.1024456576115;3800.049987503124;3841.327767640652;4105.117399759066;4110.958890411088;4125.876223713293;1122.7249323915694;1173.735909420382;1192.4514723227326;1214.9453274602663;1218.9329586872718;15373.692526149527;15440.305186266645;15441.382344706199;15493.457728979545;15494.845051549453;4740.786664600101;5075.8223462179385;5389.622726409192;5468.7265636718;5750;12666.683332916751;12673.17624354414;12675.683107922376;12685.746285074247;12696.87303126957;5311.203319502106;6427.55007149495;6519.771807986698;6523.249826269945;6524.3149469305035;5793.304803423616;5953.404659534099;5975.731092100866;5995.760169593254;6014.548908831855;3943.6647884930103;4062.939055914164;4287.506936702257;4408.691391602557;4486.057927835126;1211.4064108586906;1270.6061121052503;1378.7656098463285;1422.4594654031403;1425.9001869869217;759.5708424740114;760.6386966191126;761.9695212191561;776.3478677910582;792.0811858244409;6035.637861728223;6365.681715914186;6539.934600653933;6553.344665533408;6622.450406536552;871.6295574380863;876.1151237250295;889.0798024045212;892.6117139044406;918.6876462002951;3677;3690.723195760275;3745;3805.0487378155462;3811.6760075393904;10681.305715128492;12211.084168687385;13825.963052770881;14338.354774035193;14367.13797172173;13902.721945561032;14225.359858806172;14240.857591424161;14241.928790355982;14243.359048842827;10078.092971632588;10331.845022324671;10340.638077667245;10340.948295258475;10342.965703429747;6347.71435285404;6377.266614339224;6406.615603063831;6646;6658;10997.61508347204;11915.344656044;12640.55758048464;12658.177218480769;12696.492140314283;12112.454939527566;12448.06639502059;12452.937735311265;12464.252144871336;12474.875251247735;9016.18854303116;9254.768630784285;9417.952910235404;9424.858627120735;9434.622615095319;10290.948545257226;10317.17462603005;10365.896341036492;10369.32599381068;10428.478576071015;8653.956730216309;8830.955845220733;8858.867116993122;8929.151730585583;8939.106089391009;8242.464239824392;8452.830943380975;8577;8688.782780430287;8698.956505217433;7915.44591878563;7933.444658873824;7991.24083212094;8073.19268073188;8081;5702.7718891243885;5792.986227410271;5931.258592675916;6070.573415247325;6179.5056395489155;5984.850378740566;6045.335013148462;6112.113743507255;6183.907241391293;6320.778772742932 12:18:58 INFO - PID 1640 | |4;prototype.html;394.8065447930514;402.9738067025644;403.41303403547823;409.2102242671643;412.3443724478079;5009.423916249632;5039.546440820325;6042.9093563596525;6147.415995480422;6179.691015449232;5772.797952071678;5880.3825598312205;5939.67331796751;5974.701264936747;6006.279246490416;3964.900877478072;4262.806414204012;4379.438028753183;4426.44669416323;4704.988427488098;4081.551029386765;4499.505054444008;4702.036082603076;4702.083093796711;4732.124556956974;4212.936805947913;4448.621867141281;4494.280915053592;4507.4816396114375;4677.83627573035;3879.8254078566533;4429.867103986886;4458.9331160032625;4460.553944605546;4474.194644963905;3837.9232415351817;3851.9007141108164;3858.0740622250737;3869.7097717671286;3884.7863367514774;5530.032244357221;5809.912851307234;5883.911741323884;5886.646801191942;5924.644521328691;4826.300186472942;5257.973710131463;5260.579153667697;5284.286621306152;5287.762050707689;5818.854528636775;6237.877182107219;6413.262474815363;6416.550841441101;6429.646369449679;4248.596383343577;4282.1737653148775;4314.482262128533;4334.328179132208;4348.89127771805;5473;5640.774369025234;6380.755752628239;6660.367265109807;6783.796486105375;3559.4660800879556;4037.2934736421007;4061.740860333306;4378.5183629800695;4388.495323037828;4089.7750623715388;4212.389203565466;4418.690691651554;4471.910561788746;4474.373587697725;4341.435613370241;4490.528494508095;5073.898522029539;5230.5863431349135;5289.047971365191;5355.437679043722;5568.1534388891505;6459.838504037437;6489.643069631121;6497.707603157869;1317.9209247444992;1503.9248037598077;1510.9924450377678;1530.869876060525;1535.278419143001;851.6721062391024;976.7509285132388;999.860019597257;1002.4887307473241;1005.4771518810323;5207.609429292818;5934.584579079423;5960.940390596039;5964.582479226413;5969.671668058211;5575.553955683536;5771.422857714195;6158.04550294705;6553.411402495987;6639.236487803964;5750.769969201185;5947.345791962881;5982.858999025547;6030.216071910711;6131.264248290234;3167.1448708848943;3469.2020835208045;3476.461148521983;3496.7727097738566;3524.594671612746;6425.5502114851515;6709.630970296681;6725.125733654691;6812.216595091529;6863.794086177422;5351.518363347317;6424.646644434601;6794.490413219029;6804.319568043156;6828.117895704595;5038.496150385005;5329.680219186861;6709.228438729511;6739.550996535769;6770.187577490733;5803.593748437569;6499.285078641251;6606.702698378681;6616;6675.699593518206;4933.605311575137;6531.640759758259;6555.4100130986535;6555.934440655723;6575.736970521126;6259;6663.26704062543;6822.931770682229;6849.965750171217;6868.381845633917;3369.595648522193;4376.71551349174;4378.890527736832;4395.648348132077;4396.318570621559;6426.389492998158;6571.474282057519;6608.801735947929;6613.768518101843;6638;5547.611667183259;6340.334264902119;6371.96814015927;6385.074164246251;6420.6789660517725;5690.918725442153;5748.798792042258;6337.493000560036;6384.329645387167;6386.521010924106;6203.286622038343;6209.813705588659;6218.626882387071;6225.3774622537385;6231.719572619152;5792.972459639092;6351.96824015877;6353.777617783356;6362.681865906594;6380.840478988062;5386.276391554665;5834.299884013945;6093.086037094471;6179.28938172116;6190.288116866618 12:18:58 INFO - PID 1640 | |5;yui.html;679.259607028339;703.7508422549975;703.7853454696319;725.6589402980601;747.8728616135263;14318.570442886718;16552.172391380413;16570.33718651254;16587.00477971321;16590.25343859526;15213.619659508518;15341.309641066151;15366;15392.384304627829;15397.461088861892;940.2759874896343;944.5607792376544;947.0955237747969;948.5020364308724;954.1126752120525;945.9243260539175;956.1538038835611;956.7512446763822;958.4441024205979;963.2775418436173;981.6466072213997;1010.0505524806658;1013.7820368620745;1020.6172685243034;1021.1218352217087;1000.1498726082845;1018.7300365403174;1019.4240254256322;1021.3004092196795;1022.437659287389;749.2657195948002;749.3967356278193;749.7675720526654;750.5909279442717;750.8948747175345;16454.67090658192;16547.089910054943;16749.078800665957;16958.982461052252;16980.320787168497;14550.054246473945;14686.779698304534;14812.835547638508;14990.625234369229;15652.060876347341;10007.059127650893;11407.942960285229;11807.58673446434;11848.822267665992;12423.378831058411;2963.754881434951;3032.010090082893;3053.9847300763354;3067.877284908601;3103.8603262853;109684;112892.4355378226;113972;114785;115280.84719152785;11503.654890353302;11565.826512602318;11608.419579021016;11625.011873990805;12028.158028938065;22208.55582888333;24900;25363.746362536138;25385.365365866;25466.872665636925;7902;7971.043474782947;8064.758057258292;8110.107888132301;8144.244545468047;4028.4561584186345;4108.137291168829;4114.958850411517;4137.234611596864;4139.9793001035405;4130.235906357273;4147.087640719037;4199.0552125771455;4236.195122926696;4237.555056719;832.1636755061124;836.9916300836913;845.3448577352601;847.7626264645909;862;509.6101482365993;516.8036146264395;530.0282947435534;559.7173427419127;583.0176153181817;8863.64545418189;9825.238469419264;10188.083072523508;10366.896331036744;10396;542.7015141672065;543.61131790769;579.5754066176397;586.4985437451003;607.3718266806871;4353.151135528541;4646.605038571759;4753.33453316536;4785.665003449794;4873.488283730228;104004;104216.95783042072;106007.9399205998;106689.46655266674;109634.45182774035;69663;71336.92994605085;90593.64109538362;92710.53644731732;93278;80060;80267;81859.59070204849;81895;82188;8651.351148663749;9568.521573921136;9658.517074146124;9697.418154910727;9783.0706082922;39692;40267;40499.59500404958;40788.79605601953;41054.79472602737;51618.22572661338;52473.47526524686;52842;52934;53527.19709204443;11378.772424551627;11631.59289424866;11689.240199387013;11873;12519.874801251872;12528.874711253135;13104.541341053018;13147.934260328637;13407.664808379868;13424.731505369642;11352.716182095513;11763.235389699643;12026.857448542374;12190.451429685863;12320.630381088582;11640.338279878717;11767.941160294486;11909.285442873455;11926.991336529882;12231.449584768528;11580.189386742848;11813.409329533317;11818.46816893259;11945.761084778429;12201.633950981484;6853.897191542231;8335.874961875697;8428.199321064489;8449.45078569891;8709.259712924219;8350.665973360998;8499.74500764978;8506.276966457921;8693.217610415093;9228.462038862346 12:18:58 INFO - PID 1640 | __end_tp_report 12:18:58 INFO - PID 1640 | __start_cc_report 12:18:58 INFO - PID 1640 | _x_x_mozilla_cycle_collect,1736 12:18:58 INFO - PID 1640 | __end_cc_report 12:18:58 INFO - PID 1640 | __startTimestamp1490296738106__endTimestamp 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | ------- Summary: start ------- 12:18:58 INFO - PID 1640 | Number of tests: 6 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6068.12 Median:6339.78 stddev:810.36 (12.8%) stddev-sans-first:771.02 12:18:58 INFO - PID 1640 | Values: 2792.1 3129.5 3340.2 3499.4 3569.9 5983.9 6118.4 6249.5 6252.6 6255.7 6017.1 6098.4 6104.6 6108.2 6111.3 5392.1 5424.3 5444.1 5449.6 5561.0 5276.6 5608.3 5608.5 5659.5 5664.7 5615.6 5627.7 5632.8 5638.1 5639.0 792.2 5451.5 5455.2 5585.9 5619.0 5439.2 5503.6 5511.4 5543.2 5547.6 6126.8 6180.6 6189.8 6197.9 6209.3 6316.6 6387.9 6570.8 6582.5 6594.6 6461.2 6536.4 6626.2 6665.3 6706.7 6257.9 6298.6 6302.1 6304.8 6316.5 6718.0 6729.1 6783.1 6788.9 6794.9 6308.7 6354.2 6356.8 6358.9 6377.7 6416.7 6423.1 6434.6 6434.8 6444.9 6476.6 6486.4 6561.7 6574.7 6583.4 6481.7 6577.9 6607.2 6607.6 6619.0 5140.7 5308.5 5324.1 5341.4 5351.3 4663.2 4666.9 4712.2 4723.4 4733.8 6216.9 6248.5 6256.2 6269.8 6272.3 6650.0 6658.1 6659.4 6660.5 6662.5 6092.4 6112.1 6124.6 6172.1 6258.5 5831.7 5850.3 5850.5 5866.4 5881.3 6747.5 6790.0 6794.9 6796.8 6797.8 6381.5 6452.0 6623.4 6629.3 6682.6 6567.8 6593.8 6595.6 6599.1 6599.7 6384.6 6405.9 6415.7 6486.3 6540.1 6567.5 6609.1 6621.0 6625.5 6644.2 6399.9 6421.1 6424.1 6429.8 6436.7 6113.3 6330.8 6388.4 6410.1 6413.1 6226.9 6273.1 6339.8 6343.6 6346.9 6440.5 6443.3 6455.5 6462.9 6471.3 6325.9 6333.0 6344.6 6348.9 6350.1 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:51739.81 Median:32905.24 stddev:107128.81 (325.6%) stddev-sans-first:107360.95 12:18:58 INFO - PID 1640 | Values: 754.7 796.9 817.8 1120.5 1141.0 27727.0 28135.4 28146.6 28150.9 28171.2 28002.4 36917.6 37388.6 37454.8 37499.0 8555.6 8711.0 8721.8 8733.6 8743.5 8527.2 8685.3 8687.1 8699.0 8800.5 6122.8 6230.8 8378.5 8550.5 8552.0 8543.9 8603.9 8619.7 8623.4 8626.1 8391.7 8489.2 8491.3 8499.4 8530.4 25926.0 26265.6 36639.0 37045.4 37495.0 2871.1 3017.7 3020.8 3033.9 3044.2 22187.9 28453.0 28465.0 28884.0 29241.1 2756.2 2772.6 2820.1 2832.6 2846.6 246342.0 247288.0 247443.0 247649.0 248229.0 47046.3 47791.3 48551.8 48558.0 48734.8 50154.7 50226.7 50262.5 50269.0 50321.7 14293.5 14386.6 14415.3 14416.0 14429.7 11602.5 11627.5 11628.4 11648.2 11658.2 11340.1 13303.9 13671.8 13685.8 13775.7 4651.8 4860.7 4870.6 5631.1 5745.7 930.2 931.5 944.0 981.6 986.0 23524.9 24958.9 24982.8 25007.6 25042.5 951.5 1044.1 1089.1 1091.0 1097.0 12028.5 12676.0 12688.0 12688.9 12714.5 488621.0 493486.0 493543.0 494678.0 494705.0 56837.7 57285.9 57303.7 57308.4 57350.7 1430.0 1468.9 1507.7 1516.8 1519.6 23978.8 24267.0 24283.9 24293.9 24296.0 39411.0 39469.4 39504.0 39522.0 39533.4 420624.0 423991.0 424052.0 424465.9 427409.0 27300.0 34259.5 34748.0 34934.0 35418.0 31551.0 35849.1 43891.3 44831.3 45306.8 28280.6 28328.6 29398.3 29489.0 30290.1 27977.4 29161.6 29350.0 29971.7 30309.8 35350.5 36319.8 36444.5 36446.8 36458.0 29481.0 29549.9 30533.7 30636.5 30732.8 20564.7 22421.2 22626.2 23845.0 24614.8 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:39432.37 Median:22959.54 stddev:111958.41 (487.6%) stddev-sans-first:112234.17 12:18:58 INFO - PID 1640 | Values: 426.6 431.1 434.3 440.6 454.5 21079.9 21462.0 21817.5 22573.9 22877.9 21924.8 22218.0 22735.5 23041.2 23340.0 5477.9 5501.3 6011.3 6082.6 6311.0 6070.4 6226.1 6246.8 6248.6 6255.4 5917.7 6128.3 6202.7 6217.5 6222.0 5448.2 5942.8 5989.5 6136.8 6172.9 5388.7 5413.7 5496.9 5498.1 5498.8 24363.3 28011.0 28030.6 28112.0 28118.3 2026.6 2043.3 2053.3 2100.8 2133.4 16094.3 16464.0 16584.8 16750.4 17403.0 1622.1 1676.6 1689.8 1697.1 1701.2 121094.4 150561.0 151620.0 155908.0 157353.2 11831.9 12025.4 12220.6 12269.4 12388.3 13440.9 16320.1 16333.5 16339.3 16356.9 8516.0 8845.3 8863.4 8990.6 8997.1 7970.0 8346.6 8403.2 8532.1 8603.5 6386.4 7671.2 7706.7 7716.7 7744.5 1892.3 1920.7 2062.0 2064.3 2071.3 898.5 901.5 903.9 954.9 965.6 20571.4 21149.5 21172.8 21241.4 21298.7 940.2 940.9 974.7 983.1 983.1 10867.0 11092.1 11163.4 11187.5 11192.7 475125.0 692110.0 704728.5 710864.0 710991.0 151211.0 155121.7 157182.0 157999.2 159727.0 936.7 1152.4 1177.9 1178.0 1228.1 20951.0 21101.5 21401.5 21619.6 21963.0 58636.0 58710.7 59077.4 59934.7 62586.7 85198.0 87275.8 87364.6 88439.0 90510.5 8487.0 8489.5 8517.7 8764.8 8920.1 12034.9 13541.5 13841.0 13860.9 14072.7 11060.6 11794.0 11834.0 11876.4 11932.7 10644.9 10695.6 10927.7 11069.7 11287.5 10254.6 11522.6 11744.0 11882.0 12227.9 6802.0 8659.4 8862.6 8880.3 9152.2 9755.7 11559.8 11571.0 11992.2 12015.6 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:6812.66 Median:2506.90 stddev:4229.86 (168.7%) stddev-sans-first:4220.57 12:18:58 INFO - PID 1640 | Values: 1183.0 1204.8 1462.0 1473.2 1476.6 8830.2 9201.1 10636.4 10944.5 10966.4 10809.8 10983.9 10995.9 11029.0 11117.1 2454.5 2497.5 2499.3 2503.7 2510.1 2079.2 2150.6 2152.7 2156.0 2158.1 2344.7 2421.3 2422.6 2433.0 2433.5 2362.1 2380.0 2388.5 2389.0 2395.1 2399.1 2401.6 2423.1 2439.5 2442.8 9069.0 10998.6 11097.4 11112.9 11133.3 6149.9 6386.8 6394.6 6399.4 6411.1 3800.0 3841.3 4105.1 4111.0 4125.9 1122.7 1173.7 1192.5 1214.9 1218.9 15373.7 15440.3 15441.4 15493.5 15494.8 4740.8 5075.8 5389.6 5468.7 5750.0 12666.7 12673.2 12675.7 12685.7 12696.9 5311.2 6427.6 6519.8 6523.2 6524.3 5793.3 5953.4 5975.7 5995.8 6014.5 3943.7 4062.9 4287.5 4408.7 4486.1 1211.4 1270.6 1378.8 1422.5 1425.9 759.6 760.6 762.0 776.3 792.1 6035.6 6365.7 6539.9 6553.3 6622.5 871.6 876.1 889.1 892.6 918.7 3677.0 3690.7 3745.0 3805.0 3811.7 10681.3 12211.1 13826.0 14338.4 14367.1 13902.7 14225.4 14240.9 14241.9 14243.4 10078.1 10331.8 10340.6 10340.9 10343.0 6347.7 6377.3 6406.6 6646.0 6658.0 10997.6 11915.3 12640.6 12658.2 12696.5 12112.5 12448.1 12452.9 12464.3 12474.9 9016.2 9254.8 9418.0 9424.9 9434.6 10290.9 10317.2 10365.9 10369.3 10428.5 8654.0 8831.0 8858.9 8929.2 8939.1 8242.5 8452.8 8577.0 8688.8 8699.0 7915.4 7933.4 7991.2 8073.2 8081.0 5702.8 5793.0 5931.3 6070.6 6179.5 5984.9 6045.3 6112.1 6183.9 6320.8 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5127.43 Median:5772.11 stddev:1597.27 (27.7%) stddev-sans-first:1561.75 12:18:58 INFO - PID 1640 | Values: 394.8 403.0 403.4 409.2 412.3 5009.4 5039.5 6042.9 6147.4 6179.7 5772.8 5880.4 5939.7 5974.7 6006.3 3964.9 4262.8 4379.4 4426.4 4705.0 4081.6 4499.5 4702.0 4702.1 4732.1 4212.9 4448.6 4494.3 4507.5 4677.8 3879.8 4429.9 4458.9 4460.6 4474.2 3837.9 3851.9 3858.1 3869.7 3884.8 5530.0 5809.9 5883.9 5886.6 5924.6 4826.3 5258.0 5260.6 5284.3 5287.8 5818.9 6237.9 6413.3 6416.6 6429.6 4248.6 4282.2 4314.5 4334.3 4348.9 5473.0 5640.8 6380.8 6660.4 6783.8 3559.5 4037.3 4061.7 4378.5 4388.5 4089.8 4212.4 4418.7 4471.9 4474.4 4341.4 4490.5 5073.9 5230.6 5289.0 5355.4 5568.2 6459.8 6489.6 6497.7 1317.9 1503.9 1511.0 1530.9 1535.3 851.7 976.8 999.9 1002.5 1005.5 5207.6 5934.6 5960.9 5964.6 5969.7 5575.6 5771.4 6158.0 6553.4 6639.2 5750.8 5947.3 5982.9 6030.2 6131.3 3167.1 3469.2 3476.5 3496.8 3524.6 6425.6 6709.6 6725.1 6812.2 6863.8 5351.5 6424.6 6794.5 6804.3 6828.1 5038.5 5329.7 6709.2 6739.6 6770.2 5803.6 6499.3 6606.7 6616.0 6675.7 4933.6 6531.6 6555.4 6555.9 6575.7 6259.0 6663.3 6822.9 6850.0 6868.4 3369.6 4376.7 4378.9 4395.6 4396.3 6426.4 6571.5 6608.8 6613.8 6638.0 5547.6 6340.3 6372.0 6385.1 6420.7 5690.9 5748.8 6337.5 6384.3 6386.5 6203.3 6209.8 6218.6 6225.4 6231.7 5793.0 6352.0 6353.8 6362.7 6380.8 5386.3 5834.3 6093.1 6179.3 6190.3 12:18:58 INFO - PID 1640 | 12:18:58 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:19904.83 Median:40644.20 stddev:29442.75 (72.4%) stddev-sans-first:29489.95 12:18:58 INFO - PID 1640 | Values: 679.3 703.8 703.8 725.7 747.9 14318.6 16552.2 16570.3 16587.0 16590.3 15213.6 15341.3 15366.0 15392.4 15397.5 940.3 944.6 947.1 948.5 954.1 945.9 956.2 956.8 958.4 963.3 981.6 1010.1 1013.8 1020.6 1021.1 1000.1 1018.7 1019.4 1021.3 1022.4 749.3 749.4 749.8 750.6 750.9 16454.7 16547.1 16749.1 16959.0 16980.3 14550.1 14686.8 14812.8 14990.6 15652.1 10007.1 11407.9 11807.6 11848.8 12423.4 2963.8 3032.0 3054.0 3067.9 3103.9 109684.0 112892.4 113972.0 114785.0 115280.8 11503.7 11565.8 11608.4 11625.0 12028.2 22208.6 24900.0 25363.7 25385.4 25466.9 7902.0 7971.0 8064.8 8110.1 8144.2 4028.5 4108.1 4115.0 4137.2 4140.0 4130.2 4147.1 4199.1 4236.2 4237.6 832.2 837.0 845.3 847.8 862.0 509.6 516.8 530.0 559.7 583.0 8863.6 9825.2 10188.1 10366.9 10396.0 542.7 543.6 579.6 586.5 607.4 4353.2 4646.6 4753.3 4785.7 4873.5 104004.0 104217.0 106007.9 106689.5 109634.5 69663.0 71336.9 90593.6 92710.5 93278.0 80060.0 80267.0 81859.6 81895.0 82188.0 8651.4 9568.5 9658.5 9697.4 9783.1 39692.0 40267.0 40499.6 40788.8 41054.8 51618.2 52473.5 52842.0 52934.0 53527.2 11378.8 11631.6 11689.2 11873.0 12519.9 12528.9 13104.5 13147.9 13407.7 13424.7 11352.7 11763.2 12026.9 12190.5 12320.6 11640.3 11767.9 11909.3 11927.0 12231.4 11580.2 11813.4 11818.5 11945.8 12201.6 6853.9 8335.9 8428.2 8449.5 8709.3 8350.7 8499.7 8506.3 8693.2 9228.5 12:18:58 INFO - PID 1640 | -------- Summary: end -------- 12:18:58 INFO - PID 1640 | 12:18:58 INFO - TEST-INFO | 1640: exit 0 12:18:58 INFO - TEST-OK | dromaeo_css | took 1120919ms 12:18:58 INFO - TEST-START | kraken 12:18:58 INFO - operating with platform_type : mac_ 12:18:58 INFO - Initialising browser for kraken test... 12:18:58 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpDPMGR8/profile http://localhost:49240/getInfo.html) 12:19:01 INFO - PID 1649 | __metrics Screen width/height:1600/1200 12:19:01 INFO - PID 1649 | colorDepth:24 12:19:01 INFO - PID 1649 | Browser inner width/height: 1024/689 12:19:01 INFO - PID 1649 | __metrics 12:19:01 INFO - PID 1649 | 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" 12:19:01 INFO - PID 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:19:02 INFO - TEST-INFO | 1649: exit 0 12:19:02 INFO - Browser initialized. 12:19:02 INFO - Running cycle 1/1 for kraken test... 12:19:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpDPMGR8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:19:14 INFO - PID 1650 | RSS: Main: 233701376 12:19:14 INFO - PID 1650 | 12:19:16 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 12:19:17 INFO - PID 1650 | RSS: Main: 285171712 12:19:17 INFO - PID 1650 | 12:19:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 12:19:21 INFO - PID 1650 | RSS: Main: 286081024 12:19:21 INFO - PID 1650 | 12:19:24 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 12:19:25 INFO - PID 1650 | RSS: Main: 377753600 12:19:25 INFO - PID 1650 | 12:19:27 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 12:19:27 INFO - PID 1650 | RSS: Main: 281108480 12:19:27 INFO - PID 1650 | 12:19:29 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:19:30 INFO - PID 1650 | RSS: Main: 284274688 12:19:30 INFO - PID 1650 | 12:19:33 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 12:19:33 INFO - PID 1650 | RSS: Main: 325955584 12:19:33 INFO - PID 1650 | 12:19:36 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 12:19:37 INFO - PID 1650 | RSS: Main: 332709888 12:19:37 INFO - PID 1650 | 12:19:40 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 12:19:41 INFO - PID 1650 | RSS: Main: 335183872 12:19:41 INFO - PID 1650 | 12:19:42 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:19:43 INFO - PID 1650 | RSS: Main: 304013312 12:19:43 INFO - PID 1650 | 12:19:45 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:19:45 INFO - PID 1650 | RSS: Main: 308162560 12:19:45 INFO - PID 1650 | 12:19:48 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:19:48 INFO - PID 1650 | RSS: Main: 310759424 12:19:48 INFO - PID 1650 | 12:19:51 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:19:52 INFO - PID 1650 | RSS: Main: 326754304 12:19:52 INFO - PID 1650 | 12:19:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:19:56 INFO - PID 1650 | RSS: Main: 320348160 12:19:56 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 12:19:58 INFO - PID 1650 | RSS: Main: 381927424 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | __start_tp_report 12:19:58 INFO - PID 1650 | _x_x_mozilla_page_load 12:19:58 INFO - PID 1650 | _x_x_mozilla_page_load_details 12:19:58 INFO - PID 1650 | |i|pagename|runs| 12:19:58 INFO - PID 1650 | |0;ai-astar;112;112;110;110;110;112;112;111;111;111 12:19:58 INFO - PID 1650 | |1;audio-beat-detection;147;113;113;152;122;112;109;108;134;112 12:19:58 INFO - PID 1650 | |2;audio-dft;186;182;185;186;184;186;221;261;184;183 12:19:58 INFO - PID 1650 | |3;audio-fft;77;62;58;88;61;58;61;60;78;58 12:19:58 INFO - PID 1650 | |4;audio-oscillator;89;72;87;69;69;69;83;70;69;83 12:19:58 INFO - PID 1650 | |5;imaging-gaussian-blur;86;87;87;87;87;88;88;87;88;88 12:19:58 INFO - PID 1650 | |6;imaging-darkroom;93;93;94;94;96;94;95;95;106;151 12:19:58 INFO - PID 1650 | |7;imaging-desaturate;83;84;85;86;80;84;70;134;128;84 12:19:58 INFO - PID 1650 | |8;json-parse-financial;64;62;61;60;60;61;61;61;61;60 12:19:58 INFO - PID 1650 | |9;json-stringify-tinderbox;60;56;56;64;60;58;58;57;57;57 12:19:58 INFO - PID 1650 | |10;stanford-crypto-aes;83;83;83;84;82;83;95;105;109;83 12:19:58 INFO - PID 1650 | |11;stanford-crypto-ccm;141;146;150;150;137;140;143;154;186;160 12:19:58 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;224;236;165;216;217;192;183;182;181;186 12:19:58 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;68;67;67;68;67;67;68;67;68;66 12:19:58 INFO - PID 1650 | __end_tp_report 12:19:58 INFO - PID 1650 | __start_cc_report 12:19:58 INFO - PID 1650 | _x_x_mozilla_cycle_collect,2111 12:19:58 INFO - PID 1650 | __end_cc_report 12:19:58 INFO - PID 1650 | __startTimestamp1490296798498__endTimestamp 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | ------- Summary: start ------- 12:19:58 INFO - PID 1650 | Number of tests: 14 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:0.88 (0.8%) stddev-sans-first:0.87 12:19:58 INFO - PID 1650 | Values: 112.0 112.0 110.0 110.0 110.0 112.0 112.0 111.0 111.0 111.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:117.50 stddev:16.31 (13.9%) stddev-sans-first:14.63 12:19:58 INFO - PID 1650 | Values: 147.0 113.0 113.0 152.0 122.0 112.0 109.0 108.0 134.0 112.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:195.80 Median:186.00 stddev:25.65 (13.8%) stddev-sans-first:26.96 12:19:58 INFO - PID 1650 | Values: 186.0 182.0 185.0 186.0 184.0 186.0 221.0 261.0 184.0 183.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:66.10 Median:61.50 stddev:10.76 (17.5%) stddev-sans-first:10.67 12:19:58 INFO - PID 1650 | Values: 77.0 62.0 58.0 88.0 61.0 58.0 61.0 60.0 78.0 58.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.50 stddev:8.41 (10.8%) stddev-sans-first:7.49 12:19:58 INFO - PID 1650 | Values: 89.0 72.0 87.0 69.0 69.0 69.0 83.0 70.0 69.0 83.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.50 stddev:0.67 (0.8%) stddev-sans-first:0.53 12:19:58 INFO - PID 1650 | Values: 86.0 87.0 87.0 87.0 87.0 88.0 88.0 87.0 88.0 88.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:94.00 stddev:17.94 (19.1%) stddev-sans-first:18.79 12:19:58 INFO - PID 1650 | Values: 93.0 93.0 94.0 94.0 96.0 94.0 95.0 95.0 106.0 151.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:84.00 stddev:21.20 (25.2%) stddev-sans-first:22.25 12:19:58 INFO - PID 1650 | Values: 83.0 84.0 85.0 86.0 80.0 84.0 70.0 134.0 128.0 84.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:61.00 stddev:1.20 (2.0%) stddev-sans-first:0.67 12:19:58 INFO - PID 1650 | Values: 64.0 62.0 61.0 60.0 60.0 61.0 61.0 61.0 61.0 60.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:2.45 (4.2%) stddev-sans-first:2.52 12:19:58 INFO - PID 1650 | Values: 60.0 56.0 56.0 64.0 60.0 58.0 58.0 57.0 57.0 57.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:83.00 stddev:10.25 (12.4%) stddev-sans-first:10.64 12:19:58 INFO - PID 1650 | Values: 83.0 83.0 83.0 84.0 82.0 83.0 95.0 105.0 109.0 83.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:150.00 stddev:14.23 (9.5%) stddev-sans-first:14.65 12:19:58 INFO - PID 1650 | Values: 141.0 146.0 150.0 150.0 137.0 140.0 143.0 154.0 186.0 160.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.20 Median:204.00 stddev:23.20 (11.4%) stddev-sans-first:22.65 12:19:58 INFO - PID 1650 | Values: 224.0 236.0 165.0 216.0 217.0 192.0 183.0 182.0 181.0 186.0 12:19:58 INFO - PID 1650 | 12:19:58 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:19:58 INFO - PID 1650 | Values: 68.0 67.0 67.0 68.0 67.0 67.0 68.0 67.0 68.0 66.0 12:19:58 INFO - PID 1650 | -------- Summary: end -------- 12:19:58 INFO - PID 1650 | 12:19:59 INFO - TEST-INFO | 1650: exit 0 12:19:59 INFO - TEST-OK | kraken | took 60638ms 12:19:59 INFO - SUITE-END | took 1181s 12:19:59 INFO - Completed test suite (00:19:41) 12:19:59 INFO - javascript benchmark 12:19:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7576.593552973057, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2792.0786140573614, 3129.467990441625, 3340.248444100077, 3499.405101132807, 3569.910752231194, 5983.880322393549, 6118.449339559439, 6249.5000399968, 6252.562320637546, 6255.6559389233635, 6017.097435384694, 6098.39016098391, 6104.572679912408, 6108.175396321495, 6111.297200821896, 5392.137258038714, 5424.294841670578, 5444.101723215665, 5449.64577302474, 5560.972195139018, 5276.577873770108, 5608.327000759915, 5608.495235428811, 5659.490645841874, 5664.688442135681, 5615.634983726065, 5627.71861406928, 5632.831015069554, 5638.069718496443, 5638.956792993309, 792.1913117154237, 5451.48210919962, 5455.236266922634, 5585.916211256835, 5618.971905140468, 5439.184122381635, 5503.587230957694, 5511.448855114497, 5543.196236545718, 5547.55619550435, 6126.785562505335, 6180.567360284782, 6189.81430557084, 6197.876042479125, 6209.348018458042, 6316.62100273985, 6387.93612063878, 6570.802875913682, 6582.506312026617, 6594.604323740591, 6461.19235095613, 6536.411722944958, 6626.2048554173325, 6665.266820649725, 6706.664666766642, 6257.906131408033, 6298.622082675054, 6302.054691796176, 6304.779332723333, 6316.526260530432, 6718.025886246564, 6729.091572637729, 6783.084283621746, 6788.898166527506, 6794.932050679528, 6308.653024083719, 6354.205724284465, 6356.8410789729705, 6358.872822543523, 6377.71300291488, 6416.711247993851, 6423.13287706163, 6434.581752186093, 6434.8391290218115, 6444.903326450107, 6476.6114033158165, 6486.383793539606, 6561.737530498822, 6574.704138313788, 6583.374579414948, 6481.740730370732, 6577.934220657828, 6607.174103237096, 6607.603543787388, 6619.040239165391, 5140.665856719302, 5308.495692909168, 5324.068288049653, 5341.385740639798, 5351.25082488452, 4663.160631086369, 4666.906661866744, 4712.24604063355, 4723.409573803275, 4733.83431579893, 6216.937830621727, 6248.4688801451475, 6256.186695729526, 6269.811905642838, 6272.341404152589, 6650.0, 6658.067870498134, 6659.433948114368, 6660.500462465335, 6662.5003124765835, 6092.421219984094, 6112.077967281658, 6124.564025909317, 6172.074188871705, 6258.468030217391, 5831.650100993954, 5850.3272123705465, 5850.47345738877, 5866.413358664099, 5881.2942446906645, 6747.493937954771, 6790.0, 6794.898076528757, 6796.796096117125, 6797.762078327235, 6381.521385895983, 6451.967740161269, 6623.437007854193, 6629.270780214269, 6682.565633234019, 6567.835804104936, 6593.769218077537, 6595.637239951657, 6599.142111525471, 6599.670016499252, 6384.648844313608, 6405.871882562229, 6415.743370265323, 6486.254080780771, 6540.051692504465, 6567.507436942153, 6609.074729537772, 6621.039949207434, 6625.536212465274, 6644.235912869888, 6399.8720025598295, 6421.133147025092, 6424.068510066108, 6429.807105786834, 6436.67816609177, 6113.327533971348, 6330.841728956813, 6388.425041746265, 6410.1025856381075, 6413.134226879311, 6226.906596401148, 6273.121762953281, 6339.778107766206, 6343.5876668016435, 6346.936530634634, 6440.484761218998, 6443.291237963914, 6455.4512866405, 6462.903056454251, 6471.288158302489, 6325.905111423241, 6333.018382150774, 6344.619322840645, 6348.936510634834, 6350.142730731477], "value": 6012.912588771285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.7132089805873, 796.9242921922418, 817.7914631768898, 1120.518177183811, 1141.0301243942654, 27727.0, 28135.437291254162, 28146.57780133297, 28150.859245703767, 28171.15486535402, 28002.439951200962, 36917.630823691754, 37388.62611373892, 37454.8127259364, 37499.0, 8555.61499732512, 8711.0, 8721.825563488726, 8733.606987685569, 8743.475391476532, 8527.232549070583, 8685.34859885508, 8687.087855775148, 8699.0, 8800.515971621558, 6122.846928826771, 6230.844228894269, 8378.539180345078, 8550.486970781742, 8551.957240213822, 8543.914560854406, 8603.913960860375, 8619.698310559132, 8623.353248506324, 8626.137386261387, 8391.664333426656, 8489.151084891524, 8491.320694344438, 8499.36254780894, 8530.360222983301, 25926.0, 26265.606015909776, 36639.0, 37045.44431833525, 37495.0, 2871.124307086338, 3017.6982301769867, 3020.7528096569044, 3033.930940907702, 3044.1780719205913, 22187.889060554593, 28453.00414485483, 28465.0, 28884.0, 29241.122766317043, 2756.1731480555754, 2772.5702516110036, 2820.1257610140924, 2832.6317578714843, 2846.644169478815, 246342.0, 247288.0, 247443.0, 247649.0, 248229.0, 47046.29430558544, 47791.283130753065, 48551.75724121357, 48558.028839423016, 48734.781630459525, 50154.74613134628, 50226.74886625543, 50262.49737502651, 50269.0, 50321.741956451384, 14293.499727509487, 14386.640333991734, 14415.279236038155, 14416.0, 14429.711405771826, 11602.477888495037, 11627.53489860413, 11628.360440175871, 11648.242864213798, 11658.242214256046, 11340.149488788209, 13303.933480332536, 13671.794923076162, 13685.79471307931, 13775.655608609864, 4651.845121478023, 4860.73265970368, 4870.586000190024, 5631.1271752878365, 5745.712714364265, 930.1767935377104, 931.4644079654225, 943.9858402123974, 981.5533932060895, 985.975350616226, 23524.88237558801, 24958.875205623855, 24982.750172498407, 25007.62488562673, 25042.499150016898, 951.5480146930153, 1044.102072217892, 1089.1069323155095, 1091.0, 1096.9670909872716, 12028.45871935765, 12675.985921126288, 12688.0, 12688.87311126877, 12714.491420343267, 488621.0, 493486.0, 493543.0, 494678.0, 494705.0, 56837.715811421505, 57285.85428291411, 57303.71348143233, 57308.42691573114, 57350.71324643434, 1429.9714005720036, 1468.889833262531, 1507.6758496923214, 1516.8028156339606, 1519.6352875309847, 23978.77082687807, 24267.029318827048, 24283.90722417531, 24293.87853060794, 24296.028158873447, 39411.0, 39469.40795888007, 39504.01239968909, 39522.0, 39533.40699889446, 420624.0, 423991.0, 424052.0, 424465.8776706097, 427409.0, 27300.0, 34259.486107707904, 34748.0, 34933.951981440594, 35418.0, 31551.0, 35849.1037724059, 43891.34162987622, 44831.32753008773, 45306.77346613246, 28280.575791363557, 28328.57507137436, 29398.265043374086, 29489.0, 30290.09129726112, 27977.44045119127, 29161.56257656179, 29350.0, 29971.700282997765, 30309.848450758487, 35350.46974295336, 36319.818400907825, 36444.54221831203, 36446.817765911, 36458.0, 29480.968166114082, 29549.852250738608, 30533.694663053084, 30636.540451892793, 30732.84633576818, 20564.69152962677, 22421.215257465912, 22626.22023720341, 23845.04619815188, 24614.753852461246], "value": 17199.25845073822, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.5859364249346, 431.1420273655423, 434.25091716788546, 440.5704438172782, 454.53867308524343, 21079.894600526994, 21462.0, 21817.454563635896, 22573.887130564366, 22877.885610571924, 21924.780752192473, 22218.00018999145, 22735.545289094207, 23041.193558225466, 23340.0, 5477.931803298358, 5501.257330260403, 6011.278646562397, 6082.604630699001, 6311.0217916223055, 6070.392960703938, 6226.066090086478, 6246.750129994794, 6248.593841400318, 6255.4370106690385, 5917.704114794265, 6128.3258841527395, 6202.72087756052, 6217.471514921237, 6222.03558448439, 5448.155535891943, 5942.821715348547, 5989.460948514655, 6136.7545298188015, 6172.876542469148, 5388.703621300827, 5413.675179489204, 5496.862578435531, 5498.092814685572, 5498.780048798043, 24363.26910192697, 28011.019614313605, 28030.579541306895, 28112.0, 28118.2970425741, 2026.6048120616485, 2043.3154893110825, 2053.2813515269686, 2100.8004239597212, 2133.4453042208984, 16094.275757590936, 16464.0, 16584.75122873158, 16750.41373551932, 17403.0, 1622.1459040568502, 1676.639522502668, 1689.7718807960891, 1697.1429428138833, 1701.2344444999799, 121094.3945280268, 150561.0, 151620.0, 155908.0, 157353.21323393425, 11831.940840295916, 12025.398730063462, 12220.633380998584, 12269.386530673431, 12388.318642474751, 13440.93279533596, 16320.102394368187, 16333.509994700178, 16339.264733086802, 16356.93679910802, 8516.0, 8845.336599754915, 8863.423877447945, 8990.55047247635, 8997.100289970951, 7970.0, 8346.582670866432, 8403.243708066304, 8532.061473237938, 8603.526806025604, 6386.425221730066, 7671.232876712395, 7706.73026444083, 7716.691332346755, 7744.49660772048, 1892.2714754819494, 1920.6830872906094, 2062.0, 2064.308456667003, 2071.3475255294534, 898.5058217980215, 901.4636291406603, 903.8553831386946, 954.9140577347933, 965.6234068713208, 20571.38285851427, 21149.471263218544, 21172.78827211739, 21241.36275911725, 21298.680519792215, 940.2149205413419, 940.9012053734259, 974.7222041718218, 983.0562659846485, 983.149575617086, 10867.0, 11092.057175140042, 11163.441827908571, 11187.4965626547, 11192.720181995515, 475125.0, 692110.0, 704728.476357615, 710864.0, 710991.0, 151211.0, 155121.73852724553, 157182.0, 157999.21000394924, 159727.0, 936.681528280388, 1152.4065106470175, 1177.8763229860742, 1178.0457829158408, 1228.10962052513, 20950.952452377624, 21101.472463188544, 21401.464963375416, 21619.567608648053, 21963.0, 58636.0, 58710.70644646749, 59077.40922590719, 59934.70032649809, 62586.68706656437, 85198.0, 87275.8181045479, 87364.6317684126, 88439.0, 90510.54744726498, 8486.957565212135, 8489.490630562186, 8517.74446766598, 8764.78088047804, 8920.107989201288, 12034.887182235187, 13541.526046588322, 13841.031127820956, 13860.930695346458, 14072.718545629235, 11060.557577697124, 11793.997510211384, 11834.0, 11876.406179690808, 11932.701682458008, 10644.89355106439, 10695.57217711314, 10927.726806829574, 11069.667909962714, 11287.548498060314, 10254.6410875619, 11522.596709115141, 11744.0, 11882.049436044923, 12227.877721222674, 6802.01370801241, 8659.393842430969, 8862.64549418016, 8880.28957683371, 9152.17630413266, 9755.707328779865, 11559.768804623693, 11571.0, 11992.220505667105, 12015.579454719043], "value": 10357.267982700012, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1182.9645110646672, 1204.7831390349738, 1461.9561413157603, 1473.197107576371, 1476.6456050547865, 8830.249428798552, 9201.079892010795, 10636.414997175152, 10944.45277736114, 10966.396848173366, 10809.83785243222, 10983.890161098387, 10995.890041099587, 11029.0, 11117.055050320732, 2454.4600187958663, 2497.4880149569385, 2499.3126890105277, 2503.674522312097, 2510.134003768697, 2079.241077006897, 2150.569886022794, 2152.7201463809683, 2156.02978659603, 2158.147531724968, 2344.6834677318516, 2421.3462365161395, 2422.6244932035474, 2433.0, 2433.452473193535, 2362.102401087593, 2380.0241900820665, 2388.522295540899, 2388.9727417210593, 2395.137750409851, 2399.1483023526744, 2401.579723548372, 2423.127674037345, 2439.536488067279, 2442.755724427561, 9069.0, 10998.560057597686, 11097.44512774358, 11112.944435277852, 11133.276337038147, 6149.938500615026, 6386.776462823826, 6394.552381333308, 6399.36006399361, 6411.1024456576115, 3800.049987503124, 3841.327767640652, 4105.117399759066, 4110.958890411088, 4125.876223713293, 1122.7249323915694, 1173.735909420382, 1192.4514723227326, 1214.9453274602663, 1218.9329586872718, 15373.692526149527, 15440.305186266645, 15441.382344706199, 15493.457728979545, 15494.845051549453, 4740.786664600101, 5075.8223462179385, 5389.622726409192, 5468.7265636718, 5750.0, 12666.683332916751, 12673.17624354414, 12675.683107922376, 12685.746285074247, 12696.87303126957, 5311.203319502106, 6427.55007149495, 6519.771807986698, 6523.249826269945, 6524.3149469305035, 5793.304803423616, 5953.404659534099, 5975.731092100866, 5995.760169593254, 6014.548908831855, 3943.6647884930103, 4062.939055914164, 4287.506936702257, 4408.691391602557, 4486.057927835126, 1211.4064108586906, 1270.6061121052503, 1378.7656098463285, 1422.4594654031403, 1425.9001869869217, 759.5708424740114, 760.6386966191126, 761.9695212191561, 776.3478677910582, 792.0811858244409, 6035.637861728223, 6365.681715914186, 6539.934600653933, 6553.344665533408, 6622.450406536552, 871.6295574380863, 876.1151237250295, 889.0798024045212, 892.6117139044406, 918.6876462002951, 3677.0, 3690.723195760275, 3745.0, 3805.0487378155462, 3811.6760075393904, 10681.305715128492, 12211.084168687385, 13825.963052770881, 14338.354774035193, 14367.13797172173, 13902.721945561032, 14225.359858806172, 14240.857591424161, 14241.928790355982, 14243.359048842827, 10078.092971632588, 10331.845022324671, 10340.638077667245, 10340.948295258475, 10342.965703429747, 6347.71435285404, 6377.266614339224, 6406.615603063831, 6646.0, 6658.0, 10997.61508347204, 11915.344656044, 12640.55758048464, 12658.177218480769, 12696.492140314283, 12112.454939527566, 12448.06639502059, 12452.937735311265, 12464.252144871336, 12474.875251247735, 9016.18854303116, 9254.768630784285, 9417.952910235404, 9424.858627120735, 9434.622615095319, 10290.948545257226, 10317.17462603005, 10365.896341036492, 10369.32599381068, 10428.478576071015, 8653.956730216309, 8830.955845220733, 8858.867116993122, 8929.151730585583, 8939.106089391009, 8242.464239824392, 8452.830943380975, 8577.0, 8688.782780430287, 8698.956505217433, 7915.44591878563, 7933.444658873824, 7991.24083212094, 8073.19268073188, 8081.0, 5702.7718891243885, 5792.986227410271, 5931.258592675916, 6070.573415247325, 6179.5056395489155, 5984.850378740566, 6045.335013148462, 6112.113743507255, 6183.907241391293, 6320.778772742932], "value": 5178.899603971333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.8065447930514, 402.9738067025644, 403.41303403547823, 409.2102242671643, 412.3443724478079, 5009.423916249632, 5039.546440820325, 6042.9093563596525, 6147.415995480422, 6179.691015449232, 5772.797952071678, 5880.3825598312205, 5939.67331796751, 5974.701264936747, 6006.279246490416, 3964.900877478072, 4262.806414204012, 4379.438028753183, 4426.44669416323, 4704.988427488098, 4081.551029386765, 4499.505054444008, 4702.036082603076, 4702.083093796711, 4732.124556956974, 4212.936805947913, 4448.621867141281, 4494.280915053592, 4507.4816396114375, 4677.83627573035, 3879.8254078566533, 4429.867103986886, 4458.9331160032625, 4460.553944605546, 4474.194644963905, 3837.9232415351817, 3851.9007141108164, 3858.0740622250737, 3869.7097717671286, 3884.7863367514774, 5530.032244357221, 5809.912851307234, 5883.911741323884, 5886.646801191942, 5924.644521328691, 4826.300186472942, 5257.973710131463, 5260.579153667697, 5284.286621306152, 5287.762050707689, 5818.854528636775, 6237.877182107219, 6413.262474815363, 6416.550841441101, 6429.646369449679, 4248.596383343577, 4282.1737653148775, 4314.482262128533, 4334.328179132208, 4348.89127771805, 5473.0, 5640.774369025234, 6380.755752628239, 6660.367265109807, 6783.796486105375, 3559.4660800879556, 4037.2934736421007, 4061.740860333306, 4378.5183629800695, 4388.495323037828, 4089.7750623715388, 4212.389203565466, 4418.690691651554, 4471.910561788746, 4474.373587697725, 4341.435613370241, 4490.528494508095, 5073.898522029539, 5230.5863431349135, 5289.047971365191, 5355.437679043722, 5568.1534388891505, 6459.838504037437, 6489.643069631121, 6497.707603157869, 1317.9209247444992, 1503.9248037598077, 1510.9924450377678, 1530.869876060525, 1535.278419143001, 851.6721062391024, 976.7509285132388, 999.860019597257, 1002.4887307473241, 1005.4771518810323, 5207.609429292818, 5934.584579079423, 5960.940390596039, 5964.582479226413, 5969.671668058211, 5575.553955683536, 5771.422857714195, 6158.04550294705, 6553.411402495987, 6639.236487803964, 5750.769969201185, 5947.345791962881, 5982.858999025547, 6030.216071910711, 6131.264248290234, 3167.1448708848943, 3469.2020835208045, 3476.461148521983, 3496.7727097738566, 3524.594671612746, 6425.5502114851515, 6709.630970296681, 6725.125733654691, 6812.216595091529, 6863.794086177422, 5351.518363347317, 6424.646644434601, 6794.490413219029, 6804.319568043156, 6828.117895704595, 5038.496150385005, 5329.680219186861, 6709.228438729511, 6739.550996535769, 6770.187577490733, 5803.593748437569, 6499.285078641251, 6606.702698378681, 6616.0, 6675.699593518206, 4933.605311575137, 6531.640759758259, 6555.4100130986535, 6555.934440655723, 6575.736970521126, 6259.0, 6663.26704062543, 6822.931770682229, 6849.965750171217, 6868.381845633917, 3369.595648522193, 4376.71551349174, 4378.890527736832, 4395.648348132077, 4396.318570621559, 6426.389492998158, 6571.474282057519, 6608.801735947929, 6613.768518101843, 6638.0, 5547.611667183259, 6340.334264902119, 6371.96814015927, 6385.074164246251, 6420.6789660517725, 5690.918725442153, 5748.798792042258, 6337.493000560036, 6384.329645387167, 6386.521010924106, 6203.286622038343, 6209.813705588659, 6218.626882387071, 6225.3774622537385, 6231.719572619152, 5792.972459639092, 6351.96824015877, 6353.777617783356, 6362.681865906594, 6380.840478988062, 5386.276391554665, 5834.299884013945, 6093.086037094471, 6179.28938172116, 6190.288116866618], "value": 4634.816533386769, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.259607028339, 703.7508422549975, 703.7853454696319, 725.6589402980601, 747.8728616135263, 14318.570442886718, 16552.172391380413, 16570.33718651254, 16587.00477971321, 16590.25343859526, 15213.619659508518, 15341.309641066151, 15366.0, 15392.384304627829, 15397.461088861892, 940.2759874896343, 944.5607792376544, 947.0955237747969, 948.5020364308724, 954.1126752120525, 945.9243260539175, 956.1538038835611, 956.7512446763822, 958.4441024205979, 963.2775418436173, 981.6466072213997, 1010.0505524806658, 1013.7820368620745, 1020.6172685243034, 1021.1218352217087, 1000.1498726082845, 1018.7300365403174, 1019.4240254256322, 1021.3004092196795, 1022.437659287389, 749.2657195948002, 749.3967356278193, 749.7675720526654, 750.5909279442717, 750.8948747175345, 16454.67090658192, 16547.089910054943, 16749.078800665957, 16958.982461052252, 16980.320787168497, 14550.054246473945, 14686.779698304534, 14812.835547638508, 14990.625234369229, 15652.060876347341, 10007.059127650893, 11407.942960285229, 11807.58673446434, 11848.822267665992, 12423.378831058411, 2963.754881434951, 3032.010090082893, 3053.9847300763354, 3067.877284908601, 3103.8603262853, 109684.0, 112892.4355378226, 113972.0, 114785.0, 115280.84719152785, 11503.654890353302, 11565.826512602318, 11608.419579021016, 11625.011873990805, 12028.158028938065, 22208.55582888333, 24900.0, 25363.746362536138, 25385.365365866, 25466.872665636925, 7902.0, 7971.043474782947, 8064.758057258292, 8110.107888132301, 8144.244545468047, 4028.4561584186345, 4108.137291168829, 4114.958850411517, 4137.234611596864, 4139.9793001035405, 4130.235906357273, 4147.087640719037, 4199.0552125771455, 4236.195122926696, 4237.555056719, 832.1636755061124, 836.9916300836913, 845.3448577352601, 847.7626264645909, 862.0, 509.6101482365993, 516.8036146264395, 530.0282947435534, 559.7173427419127, 583.0176153181817, 8863.64545418189, 9825.238469419264, 10188.083072523508, 10366.896331036744, 10396.0, 542.7015141672065, 543.61131790769, 579.5754066176397, 586.4985437451003, 607.3718266806871, 4353.151135528541, 4646.605038571759, 4753.33453316536, 4785.665003449794, 4873.488283730228, 104004.0, 104216.95783042072, 106007.9399205998, 106689.46655266674, 109634.45182774035, 69663.0, 71336.92994605085, 90593.64109538362, 92710.53644731732, 93278.0, 80060.0, 80267.0, 81859.59070204849, 81895.0, 82188.0, 8651.351148663749, 9568.521573921136, 9658.517074146124, 9697.418154910727, 9783.0706082922, 39692.0, 40267.0, 40499.59500404958, 40788.79605601953, 41054.79472602737, 51618.22572661338, 52473.47526524686, 52842.0, 52934.0, 53527.19709204443, 11378.772424551627, 11631.59289424866, 11689.240199387013, 11873.0, 12519.874801251872, 12528.874711253135, 13104.541341053018, 13147.934260328637, 13407.664808379868, 13424.731505369642, 11352.716182095513, 11763.235389699643, 12026.857448542374, 12190.451429685863, 12320.630381088582, 11640.338279878717, 11767.941160294486, 11909.285442873455, 11926.991336529882, 12231.449584768528, 11580.189386742848, 11813.409329533317, 11818.46816893259, 11945.761084778429, 12201.633950981484, 6853.897191542231, 8335.874961875697, 8428.199321064489, 8449.45078569891, 8709.259712924219, 8350.665973360998, 8499.74500764978, 8506.276966457921, 8693.217610415093, 9228.462038862346], "value": 7357.021154562807, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 110.0, 110.0, 110.0, 112.0, 112.0, 111.0, 111.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 113.0, 152.0, 122.0, 112.0, 109.0, 108.0, 134.0, 112.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 185.0, 186.0, 184.0, 186.0, 221.0, 261.0, 184.0, 183.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 62.0, 58.0, 88.0, 61.0, 58.0, 61.0, 60.0, 78.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 87.0, 69.0, 69.0, 69.0, 83.0, 70.0, 69.0, 83.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 87.0, 87.0, 88.0, 88.0, 87.0, 88.0, 88.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 96.0, 94.0, 95.0, 95.0, 106.0, 151.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 86.0, 80.0, 84.0, 70.0, 134.0, 128.0, 84.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 56.0, 56.0, 64.0, 60.0, 58.0, 58.0, 57.0, 57.0, 57.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 84.0, 82.0, 83.0, 95.0, 105.0, 109.0, 83.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 150.0, 150.0, 137.0, 140.0, 143.0, 154.0, 186.0, 160.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 236.0, 165.0, 216.0, 217.0, 192.0, 183.0, 182.0, 181.0, 186.0], "value": 198.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0, 66.0], "value": 67.3, "unit": "score"}]}]} 12:19:59 INFO - Return code: 0 12:19:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:19:59 ERROR - # TBPL SUCCESS # 12:19:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:19:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:19:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:19:59 INFO - 2017-03-23 12:19:59.809 screenresolution[1654:9694] starting screenresolution argv=screenresolution get 12:19:59 INFO - 2017-03-23 12:19:59.812 screenresolution[1654:9694] Display 0: 1600x1200x32@60 12:19:59 INFO - 2017-03-23 12:19:59.818 screenresolution[1655:9697] starting screenresolution argv=screenresolution list 12:19:59 INFO - Available Modes on Display 0 12:19:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:19:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:19:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:19:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:20:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:20:00 INFO - Intel Iris: 12:20:00 INFO - Chipset Model: Intel Iris 12:20:00 INFO - Type: GPU 12:20:00 INFO - Bus: Built-In 12:20:00 INFO - VRAM (Dynamic, Max): 1536 MB 12:20:00 INFO - Vendor: Intel (0x8086) 12:20:00 INFO - Device ID: 0x0a2e 12:20:00 INFO - Revision ID: 0x0009 12:20:00 INFO - Displays: 12:20:00 INFO - Display: 12:20:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:20:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:20:00 INFO - Display Serial Number: 1600x1200 60 12:20:00 INFO - Main Display: Yes 12:20:00 INFO - Mirror: Off 12:20:00 INFO - Online: Yes 12:20:00 INFO - Rotation: Supported 12:20:00 INFO - Return code: 0 12:20:00 INFO - Running post-action listener: _resource_record_post_action 12:20:00 INFO - [mozharness: 2017-03-23 19:20:00.057413Z] Finished run-tests step (success) 12:20:00 INFO - Running post-run listener: _resource_record_post_run 12:20:00 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 39306240; Write bytes: 512032256; Read time: 386; Write time: 1698 12:20:00 INFO - TinderboxPrint: CPU usage
53.3% 12:20:00 INFO - TinderboxPrint: I/O read bytes / time
39,306,240 / 386 12:20:00 INFO - TinderboxPrint: I/O write bytes / time
512,032,256 / 1,698 12:20:00 INFO - TinderboxPrint: CPU idle
2,246.3 (46.7%) 12:20:00 INFO - TinderboxPrint: CPU system
959.3 (19.9%) 12:20:00 INFO - TinderboxPrint: CPU user
1,604.0 (33.4%) 12:20:00 INFO - TinderboxPrint: Swap in / out
249,806,848 / 0 12:20:00 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204493312; Write bytes: 203961856; Read time: 15896; Write time: 416 12:20:00 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 37475328; Write bytes: 298121216; Read time: 362; Write time: 1246 12:20:00 INFO - Running post-run listener: _upload_blobber_files 12:20:00 INFO - Blob upload gear active. 12:20:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:20:00 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 12:20:00 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'] 12:20:00 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 12:20:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:20:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:01 INFO - (blobuploader) - INFO - Done attempting. 12:20:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:20:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:02 INFO - (blobuploader) - INFO - Done attempting. 12:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:03 INFO - (blobuploader) - INFO - Done attempting. 12:20:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:20:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:20:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:20:06 INFO - (blobuploader) - INFO - Done attempting. 12:20:06 INFO - (blobuploader) - INFO - Iteration through files over. 12:20:06 INFO - Return code: 0 12:20:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:20:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:20:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f7bd71d90c0bb3095ba71e95b13b7df8893a722808d9c38d5e2a5efb0757788c7ac21db82788b161dc09f19f75f518c6c41379e30ba2bf3f91a12e9c4235fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9a783d09fa1150a36573bcf35bfa7db8a22e61a5c1820041ec9274f96808138597d5564478c3421200623314a4a2d82fcc5cdf7d98485f31b1d0e4a819e2c7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb9f15c5a597b3a4cc8ea339bc8297232c0fe94619eecbfc693d5e19817402e1362f665efb8969f0ce99016e731e5a5b047ee4a9b483a862516c3bc42f47961e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/267c04612b42882e98f46af5c94e79a13d4ff730744eea338231baf784723a6da1d5993f37f8982da6527abd8a513126ac6484735ad45fd93c82ce50286924d6"} 12:20:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:20:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:20:06 INFO - Contents: 12:20:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26f7bd71d90c0bb3095ba71e95b13b7df8893a722808d9c38d5e2a5efb0757788c7ac21db82788b161dc09f19f75f518c6c41379e30ba2bf3f91a12e9c4235fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9a783d09fa1150a36573bcf35bfa7db8a22e61a5c1820041ec9274f96808138597d5564478c3421200623314a4a2d82fcc5cdf7d98485f31b1d0e4a819e2c7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb9f15c5a597b3a4cc8ea339bc8297232c0fe94619eecbfc693d5e19817402e1362f665efb8969f0ce99016e731e5a5b047ee4a9b483a862516c3bc42f47961e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/267c04612b42882e98f46af5c94e79a13d4ff730744eea338231baf784723a6da1d5993f37f8982da6527abd8a513126ac6484735ad45fd93c82ce50286924d6"} 12:20:06 INFO - Running post-run listener: copy_logs_to_upload_dir 12:20:06 INFO - Copying logs to upload dir... 12:20:06 INFO - mkdir: /builds/slave/test/build/upload/logs 12:20:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.105155 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-23 12:20:06.085310) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 12:20:06.088910) ========= 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.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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/26f7bd71d90c0bb3095ba71e95b13b7df8893a722808d9c38d5e2a5efb0757788c7ac21db82788b161dc09f19f75f518c6c41379e30ba2bf3f91a12e9c4235fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9a783d09fa1150a36573bcf35bfa7db8a22e61a5c1820041ec9274f96808138597d5564478c3421200623314a4a2d82fcc5cdf7d98485f31b1d0e4a819e2c7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb9f15c5a597b3a4cc8ea339bc8297232c0fe94619eecbfc693d5e19817402e1362f665efb8969f0ce99016e731e5a5b047ee4a9b483a862516c3bc42f47961e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/267c04612b42882e98f46af5c94e79a13d4ff730744eea338231baf784723a6da1d5993f37f8982da6527abd8a513126ac6484735ad45fd93c82ce50286924d6"} build_url:https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/cRJ-wa0BRd22ho6-7QCJSA/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/26f7bd71d90c0bb3095ba71e95b13b7df8893a722808d9c38d5e2a5efb0757788c7ac21db82788b161dc09f19f75f518c6c41379e30ba2bf3f91a12e9c4235fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c9a783d09fa1150a36573bcf35bfa7db8a22e61a5c1820041ec9274f96808138597d5564478c3421200623314a4a2d82fcc5cdf7d98485f31b1d0e4a819e2c7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb9f15c5a597b3a4cc8ea339bc8297232c0fe94619eecbfc693d5e19817402e1362f665efb8969f0ce99016e731e5a5b047ee4a9b483a862516c3bc42f47961e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/267c04612b42882e98f46af5c94e79a13d4ff730744eea338231baf784723a6da1d5993f37f8982da6527abd8a513126ac6484735ad45fd93c82ce50286924d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 12:20:06.124636) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 12:20:06.124990) ========= 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.a3HBK113wO/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.XPync5f5Az/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005036 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 12:20:06.174364) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 12:20:06.174677) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 12:20:06.175005) ========= ========= Total master_lag: 0.18 =========