builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0153 starttime: 1490723034.74 results: success (0) buildid: 20170328095322 builduid: 2db9f9785d1042bcaaa4afcb6f286ea5 revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.741679) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.742156) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.742472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.769340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.769689) ========= 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.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-28 10:43:54-- 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% 14.3M=0.001s 2017-03-28 10:43:54 (14.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129829 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.927156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.927515) ========= 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.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.036300 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:54.987244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 10:43:54.987986) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --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 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-28 10:43:55,100 truncating revision to first 12 chars 2017-03-28 10:43:55,100 Setting DEBUG logging. 2017-03-28 10:43:55,100 attempt 1/10 2017-03-28 10:43:55,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e0eb96528a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 10:43:55,927 unpacking tar archive at: mozilla-central-0e0eb96528a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.100087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 10:43:56.112401) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:56.112803) ========= script_repo_revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:56.113250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:56.113570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 10:43:56.133829) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2017-03-28 10:43:56.134279) ========= /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.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:43:56 INFO - MultiFileLogger online at 20170328 10:43:56 in /builds/slave/test 10:43:56 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 10:43:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:43:56 INFO - {'append_to_log': False, 10:43:56 INFO - 'base_work_dir': '/builds/slave/test', 10:43:56 INFO - 'blob_upload_branch': 'Firefox', 10:43:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:43:56 INFO - 'branch': 'Firefox', 10:43:56 INFO - 'buildbot_json_path': 'buildprops.json', 10:43:56 INFO - 'config_files': ('talos/mac_config.py',), 10:43:56 INFO - 'default_actions': ('clobber', 10:43:56 INFO - 'read-buildbot-config', 10:43:56 INFO - 'download-and-extract', 10:43:56 INFO - 'populate-webroot', 10:43:56 INFO - 'create-virtualenv', 10:43:56 INFO - 'install', 10:43:56 INFO - 'run-tests'), 10:43:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:43:56 INFO - 'download_minidump_stackwalk': True, 10:43:56 INFO - 'download_symbols': 'ondemand', 10:43:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:43:56 INFO - 'tooltool.py': '/tools/tooltool.py', 10:43:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:43:56 INFO - '/tools/misc-python/virtualenv.py')}, 10:43:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:43:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:43:56 INFO - 'gecko_profile': False, 10:43:56 INFO - 'gecko_profile_interval': 0, 10:43:56 INFO - 'installer_path': 'installer.exe', 10:43:56 INFO - 'log_level': 'info', 10:43:56 INFO - 'log_name': 'talos', 10:43:56 INFO - 'log_to_console': True, 10:43:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:43:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:43:56 INFO - 'opt_config_files': (), 10:43:56 INFO - 'pip_index': False, 10:43:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:43:56 INFO - 'cmd': ('bash', 10:43:56 INFO - '-c', 10:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:43:56 INFO - 'enabled': True, 10:43:56 INFO - 'halt_on_failure': False, 10:43:56 INFO - 'name': 'check_screen_resolution'},), 10:43:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:43:56 INFO - 'cmd': ('bash', 10:43:56 INFO - '-c', 10:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:43:56 INFO - 'enabled': True, 10:43:56 INFO - 'halt_on_failure': False, 10:43:56 INFO - 'name': 'check_screen_resolution'},), 10:43:56 INFO - 'run_cmd_checks_enabled': True, 10:43:56 INFO - 'suite': 'dromaeojs', 10:43:56 INFO - 'system_bits': '32', 10:43:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:43:56 INFO - 'title': 't-yosemite-r7-0153', 10:43:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:43:56 INFO - 'use_talos_json': True, 10:43:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:43:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:43:56 INFO - 'work_dir': 'build'} 10:43:56 INFO - [mozharness: 2017-03-28 17:43:56.361735Z] Running clobber step. 10:43:56 INFO - Running pre-action listener: _resource_record_pre_action 10:43:56 INFO - Running main action method: clobber 10:43:56 INFO - rmtree: /builds/slave/test/build 10:43:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:43:58 INFO - Running post-action listener: _resource_record_post_action 10:43:58 INFO - [mozharness: 2017-03-28 17:43:58.916185Z] Finished clobber step (success) 10:43:58 INFO - [mozharness: 2017-03-28 17:43:58.916343Z] Running read-buildbot-config step. 10:43:58 INFO - Running pre-action listener: _resource_record_pre_action 10:43:58 INFO - Running main action method: read_buildbot_config 10:43:58 INFO - Using buildbot properties: 10:43:58 INFO - { 10:43:58 INFO - "project": "", 10:43:58 INFO - "product": "firefox", 10:43:58 INFO - "script_repo_revision": "production", 10:43:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:43:58 INFO - "repository": "", 10:43:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:43:58 INFO - "buildid": "20170328095322", 10:43:58 INFO - "pgo_build": "False", 10:43:58 INFO - "basedir": "/builds/slave/test", 10:43:58 INFO - "buildnumber": 61, 10:43:58 INFO - "slavename": "t-yosemite-r7-0153", 10:43:58 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:43:58 INFO - "platform": "yosemite_r7", 10:43:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:43:58 INFO - "branch": "mozilla-central", 10:43:58 INFO - "repo_path": "mozilla-central", 10:43:58 INFO - "revision": "0e0eb96528a1d032fe6ed54f67d32290d533fbfd", 10:43:58 INFO - "stage_platform": "macosx64", 10:43:58 INFO - "builduid": "2db9f9785d1042bcaaa4afcb6f286ea5", 10:43:58 INFO - "slavebuilddir": "test" 10:43:58 INFO - } 10:43:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 10:43:58 INFO - Running post-action listener: _resource_record_post_action 10:43:58 INFO - [mozharness: 2017-03-28 17:43:58.919564Z] Finished read-buildbot-config step (success) 10:43:58 INFO - [mozharness: 2017-03-28 17:43:58.919699Z] Running download-and-extract step. 10:43:58 INFO - Running pre-action listener: _resource_record_pre_action 10:43:58 INFO - Running main action method: download_and_extract 10:43:58 INFO - mkdir: /builds/slave/test/build/tests 10:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:58 INFO - trying https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:43:58 INFO - Downloading https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/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 10:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/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 10:44:00 INFO - Downloaded 1325 bytes. 10:44:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:44:00 INFO - Using the following test package requirements: 10:44:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:44:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:44:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:44:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:44:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'jsshell-mac.zip'], 10:44:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:44:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:44:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:44:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:44:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:44:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:44:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:44:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:44:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:44:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:44:01 INFO - Expected file size: 52505103 10:44:01 INFO - Obtained file size: 52505103 10:44:18 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 10:44:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:44:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:44:18 INFO - Fetch https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:44:19 INFO - Expected file size: 52505103 10:44:19 INFO - Obtained file size: 52505103 10:44:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:44:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:44:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:44:36 INFO - Expected file size: 14404171 10:44:36 INFO - Obtained file size: 14404171 10:44:37 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')]} 10:44:37 INFO - trying https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:44:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:44:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/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 10:44:39 INFO - Downloaded 75582059 bytes. 10:44:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:44:39 INFO - mkdir: /builds/slave/test/properties 10:44:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:44:39 INFO - Writing to file /builds/slave/test/properties/build_url 10:44:39 INFO - Contents: 10:44:39 INFO - build_url:https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:44:40 INFO - Running post-action listener: _resource_record_post_action 10:44:40 INFO - Running post-action listener: set_extra_try_arguments 10:44:40 INFO - [mozharness: 2017-03-28 17:44:40.074058Z] Finished download-and-extract step (success) 10:44:40 INFO - [mozharness: 2017-03-28 17:44:40.074166Z] Running populate-webroot step. 10:44:40 INFO - Running pre-action listener: _resource_record_pre_action 10:44:40 INFO - Running main action method: populate_webroot 10:44:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:44:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:44:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:44:40 INFO - '--tppagecycles', 10:44:40 INFO - '7'], 10:44:40 INFO - 'tests': ['tsvgm']}}, 10:44:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:44:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:44:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:44:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:44:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:44:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['damp', 'tps']}, 10:44:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'tests': ['damp', 'tps']}, 10:44:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['dromaeo_dom']}, 10:44:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:44:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['basic_compositor_video']}, 10:44:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:44:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'other-e10s': {'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['a11yr', 10:44:40 INFO - 'ts_paint', 10:44:40 INFO - 'tpaint', 10:44:40 INFO - 'sessionrestore', 10:44:40 INFO - 'sessionrestore_no_auto_restore', 10:44:40 INFO - 'tabpaint']}, 10:44:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['tsvgx', 10:44:40 INFO - 'tsvgr_opacity', 10:44:40 INFO - 'tart', 10:44:40 INFO - 'tscrollx', 10:44:40 INFO - 'cart', 10:44:40 INFO - 'tsvg_static']}, 10:44:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:44:40 INFO - 'tsvgr_opacity', 10:44:40 INFO - 'tart', 10:44:40 INFO - 'tscrollx', 10:44:40 INFO - 'cart', 10:44:40 INFO - 'tsvg_static']}, 10:44:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'talos_options': ['--disable-e10s'], 10:44:40 INFO - 'tests': ['tp5o']}, 10:44:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:44:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'talos_options': ['--disable-e10s', 10:44:40 INFO - '--xperf_path', 10:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:40 INFO - 'tests': ['tp5n']}, 10:44:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:44:40 INFO - 'talos_options': ['--xperf_path', 10:44:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:44:40 INFO - 'tests': ['tp5n']}}, 10:44:40 INFO - 'talos.zip': {'path': '', 10:44:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:44:40 INFO - Running post-action listener: _resource_record_post_action 10:44:40 INFO - [mozharness: 2017-03-28 17:44:40.092540Z] Finished populate-webroot step (success) 10:44:40 INFO - [mozharness: 2017-03-28 17:44:40.092644Z] Running create-virtualenv step. 10:44:40 INFO - Running pre-action listener: _resource_record_pre_action 10:44:40 INFO - Running main action method: create_virtualenv 10:44:40 INFO - Creating virtualenv /builds/slave/test/build/venv 10:44:40 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 10:44:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:44:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:44:40 INFO - Using real prefix '/tools/python27' 10:44:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:44:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:44:43 INFO - Installing pip.................done. 10:44:43 INFO - Return code: 0 10:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:44:44 INFO - Reading from file tmpfile_stdout 10:44:44 INFO - Output received: 10:44:44 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) 10:44:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:44 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 10:44:44 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 10:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:44 INFO - 'HOME': '/Users/cltbld', 10:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:44 INFO - 'LOGNAME': 'cltbld', 10:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:44 INFO - 'MOZ_NO_REMOTE': '1', 10:44:44 INFO - 'NO_EM_RESTART': '1', 10:44:44 INFO - 'PAGER': '/bin/cat', 10:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:44 INFO - 'PWD': '/builds/slave/test', 10:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:44 INFO - 'SHELL': '/bin/bash', 10:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:44 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:44 INFO - 'USER': 'cltbld', 10:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:44 INFO - 'XPC_FLAGS': '0x0', 10:44:44 INFO - 'XPC_SERVICE_NAME': '0', 10:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:44 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 10:44:44 INFO - Cleaning up... 10:44:44 INFO - Return code: 0 10:44:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:44 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:44 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 10:44:44 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 10:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:44 INFO - 'HOME': '/Users/cltbld', 10:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:44 INFO - 'LOGNAME': 'cltbld', 10:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:44 INFO - 'MOZ_NO_REMOTE': '1', 10:44:44 INFO - 'NO_EM_RESTART': '1', 10:44:44 INFO - 'PAGER': '/bin/cat', 10:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:44 INFO - 'PWD': '/builds/slave/test', 10:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:44 INFO - 'SHELL': '/bin/bash', 10:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:44 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:44 INFO - 'USER': 'cltbld', 10:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:44 INFO - 'XPC_FLAGS': '0x0', 10:44:44 INFO - 'XPC_SERVICE_NAME': '0', 10:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:44 INFO - Downloading/unpacking psutil>=3.1.1 10:44:44 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 10:44:44 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 10:44:44 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 10:44:44 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 10:44:44 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 10:44:44 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 10:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:44:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:48 INFO - Installing collected packages: psutil 10:44:48 INFO - Running setup.py install for psutil 10:44:48 INFO - building 'psutil._psutil_osx' extension 10:44:48 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 10:44:48 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 10:44:48 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 10:44:48 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 10:44:49 INFO - building 'psutil._psutil_posix' extension 10:44:49 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 10:44:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:44:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:44:49 INFO - ^ 10:44:49 INFO - 1 warning generated. 10:44:49 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 10:44:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:49 INFO - Successfully installed psutil 10:44:49 INFO - Cleaning up... 10:44:49 INFO - Return code: 0 10:44:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:44: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')]} 10:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44: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')]} 10:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:50 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:50 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 10:44:50 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 10:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:50 INFO - 'HOME': '/Users/cltbld', 10:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:50 INFO - 'LOGNAME': 'cltbld', 10:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:50 INFO - 'MOZ_NO_REMOTE': '1', 10:44:50 INFO - 'NO_EM_RESTART': '1', 10:44:50 INFO - 'PAGER': '/bin/cat', 10:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:50 INFO - 'PWD': '/builds/slave/test', 10:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:50 INFO - 'SHELL': '/bin/bash', 10:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:50 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:50 INFO - 'USER': 'cltbld', 10:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:50 INFO - 'XPC_FLAGS': '0x0', 10:44:50 INFO - 'XPC_SERVICE_NAME': '0', 10:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:44:50 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 10:44:50 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 10:44:50 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 10:44:50 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 10:44:50 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 10:44:50 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 10:44:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:44:53 INFO - Installing collected packages: mozsystemmonitor 10:44:53 INFO - Running setup.py install for mozsystemmonitor 10:44:53 INFO - Successfully installed mozsystemmonitor 10:44:53 INFO - Cleaning up... 10:44:53 INFO - Return code: 0 10:44:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:44:53 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')]} 10:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:53 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')]} 10:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:53 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:53 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 10:44:53 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 10:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:53 INFO - 'HOME': '/Users/cltbld', 10:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:53 INFO - 'LOGNAME': 'cltbld', 10:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:53 INFO - 'MOZ_NO_REMOTE': '1', 10:44:53 INFO - 'NO_EM_RESTART': '1', 10:44:53 INFO - 'PAGER': '/bin/cat', 10:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:53 INFO - 'PWD': '/builds/slave/test', 10:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:53 INFO - 'SHELL': '/bin/bash', 10:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:53 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:53 INFO - 'USER': 'cltbld', 10:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:53 INFO - 'XPC_FLAGS': '0x0', 10:44:53 INFO - 'XPC_SERVICE_NAME': '0', 10:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:53 INFO - Downloading/unpacking jsonschema==2.5.1 10:44:53 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 10:44:53 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 10:44:53 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 10:44:53 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 10:44:53 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 10:44:53 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 10:44:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:44:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:44:56 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 10:44:56 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 10:44:56 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 10:44:56 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 10:44:56 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 10:44:56 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 10:44:56 INFO - Downloading functools32-3.2.3-2.tar.gz 10:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:44:56 INFO - warning: no files found matching '*.txt' 10:44:56 INFO - no previously-included directories found matching 'build' 10:44:56 INFO - no previously-included directories found matching 'dist' 10:44:56 INFO - no previously-included directories found matching '.git*' 10:44:56 INFO - Installing collected packages: jsonschema, functools32 10:44:57 INFO - Running setup.py install for functools32 10:44:57 INFO - warning: no files found matching '*.txt' 10:44:57 INFO - no previously-included directories found matching 'build' 10:44:57 INFO - no previously-included directories found matching 'dist' 10:44:57 INFO - no previously-included directories found matching '.git*' 10:44:57 INFO - Successfully installed jsonschema functools32 10:44:57 INFO - Cleaning up... 10:44:57 INFO - Return code: 0 10:44:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:44:57 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')]} 10:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:57 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')]} 10:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:57 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:57 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 10:44:57 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 10:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:57 INFO - 'HOME': '/Users/cltbld', 10:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:57 INFO - 'LOGNAME': 'cltbld', 10:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:57 INFO - 'MOZ_NO_REMOTE': '1', 10:44:57 INFO - 'NO_EM_RESTART': '1', 10:44:57 INFO - 'PAGER': '/bin/cat', 10:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:57 INFO - 'PWD': '/builds/slave/test', 10:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:57 INFO - 'SHELL': '/bin/bash', 10:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:57 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:57 INFO - 'USER': 'cltbld', 10:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:57 INFO - 'XPC_FLAGS': '0x0', 10:44:57 INFO - 'XPC_SERVICE_NAME': '0', 10:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:44:57 INFO - Cleaning up... 10:44:57 INFO - Return code: 0 10:44:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:44:57 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')]} 10:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:57 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')]} 10:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:57 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:44:57 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 10:44:57 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 10:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:57 INFO - 'HOME': '/Users/cltbld', 10:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:57 INFO - 'LOGNAME': 'cltbld', 10:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:57 INFO - 'MOZ_NO_REMOTE': '1', 10:44:57 INFO - 'NO_EM_RESTART': '1', 10:44:57 INFO - 'PAGER': '/bin/cat', 10:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:57 INFO - 'PWD': '/builds/slave/test', 10:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:57 INFO - 'SHELL': '/bin/bash', 10:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:44:57 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:57 INFO - 'USER': 'cltbld', 10:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:57 INFO - 'XPC_FLAGS': '0x0', 10:44:57 INFO - 'XPC_SERVICE_NAME': '0', 10:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:57 INFO - Downloading/unpacking blobuploader==1.2.4 10:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:00 INFO - Downloading blobuploader-1.2.4.tar.gz 10:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:45:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:45:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:45:01 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 10:45:01 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 10:45:01 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 10:45:01 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 10:45:01 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 10:45:01 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 10:45:01 INFO - Downloading docopt-0.6.1.tar.gz 10:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:45:01 INFO - Installing collected packages: blobuploader, requests, docopt 10:45:01 INFO - Running setup.py install for blobuploader 10:45:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:45:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:45:01 INFO - Running setup.py install for requests 10:45:02 INFO - Running setup.py install for docopt 10:45:02 INFO - Successfully installed blobuploader requests docopt 10:45:02 INFO - Cleaning up... 10:45:02 INFO - Return code: 0 10:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:02 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:45:02 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 10:45:02 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 10:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:02 INFO - 'HOME': '/Users/cltbld', 10:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:02 INFO - 'LOGNAME': 'cltbld', 10:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:02 INFO - 'MOZ_NO_REMOTE': '1', 10:45:02 INFO - 'NO_EM_RESTART': '1', 10:45:02 INFO - 'PAGER': '/bin/cat', 10:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:02 INFO - 'PWD': '/builds/slave/test', 10:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:02 INFO - 'SHELL': '/bin/bash', 10:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:45:02 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:02 INFO - 'USER': 'cltbld', 10:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:02 INFO - 'XPC_FLAGS': '0x0', 10:45:02 INFO - 'XPC_SERVICE_NAME': '0', 10:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:02 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-bycXj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:02 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-FeEAP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-fiP3oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Z3Gg8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-O5LeFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UNVe54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-FSdRWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-FNz4DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-aFeSf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-rV1POR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-8gBDOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:03 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-KxBeSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:04 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-YEBal4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:04 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-QG9VHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:04 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1praSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:04 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-GMNSO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:04 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-r1qluw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:45:04 INFO - Running setup.py install for manifestparser 10:45:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:45:04 INFO - Running setup.py install for mozcrash 10:45:04 INFO - Running setup.py install for mozdebug 10:45:04 INFO - Running setup.py install for mozdevice 10:45:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Running setup.py install for mozfile 10:45:05 INFO - Running setup.py install for mozhttpd 10:45:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Running setup.py install for mozinfo 10:45:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Running setup.py install for mozInstall 10:45:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:45:05 INFO - Running setup.py install for mozleak 10:45:05 INFO - Running setup.py install for mozlog 10:45:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Running setup.py install for moznetwork 10:45:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Running setup.py install for mozprocess 10:45:06 INFO - Running setup.py install for mozprofile 10:45:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Running setup.py install for mozrunner 10:45:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:45:06 INFO - Running setup.py install for mozscreenshot 10:45:06 INFO - Running setup.py install for moztest 10:45:06 INFO - Running setup.py install for mozversion 10:45:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:45:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:45:07 INFO - Cleaning up... 10:45:07 INFO - Return code: 0 10:45:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:07 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:45:07 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 10:45:07 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 10:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:07 INFO - 'HOME': '/Users/cltbld', 10:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:07 INFO - 'LOGNAME': 'cltbld', 10:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:07 INFO - 'MOZ_NO_REMOTE': '1', 10:45:07 INFO - 'NO_EM_RESTART': '1', 10:45:07 INFO - 'PAGER': '/bin/cat', 10:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:07 INFO - 'PWD': '/builds/slave/test', 10:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:07 INFO - 'SHELL': '/bin/bash', 10:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:45:07 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:07 INFO - 'USER': 'cltbld', 10:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:07 INFO - 'XPC_FLAGS': '0x0', 10:45:07 INFO - 'XPC_SERVICE_NAME': '0', 10:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UdYYnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:07 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)) 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-_ZptYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:07 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)) 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UCLAUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:07 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)) 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1JLA_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:07 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)) 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-5H8GhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:07 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)) 10:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:07 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Lo8dkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MOsPtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-fDWcT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ZfwLek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-OwJnP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-a1nchz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-xtFi5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-AdhAZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-gYomYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:08 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)) 10:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-WEtLCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:09 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)) 10:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-srZJt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:09 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)) 10:45:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-nvDDxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:09 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)) 10:45:09 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)) 10:45:09 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)) 10:45:09 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)) 10:45:09 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)) 10:45:09 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)) 10:45:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:45:09 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 10:45:09 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 10:45:09 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 10:45:09 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 10:45:09 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 10:45:09 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 10:45:11 INFO - Downloading blessings-1.6.tar.gz 10:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:45:11 INFO - Installing collected packages: blessings 10:45:11 INFO - Running setup.py install for blessings 10:45:12 INFO - Successfully installed blessings 10:45:12 INFO - Cleaning up... 10:45:12 INFO - Return code: 0 10:45:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:45:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:12 INFO - Reading from file tmpfile_stdout 10:45:12 INFO - Current package versions: 10:45:12 INFO - blessings == 1.6 10:45:12 INFO - blobuploader == 1.2.4 10:45:12 INFO - docopt == 0.6.1 10:45:12 INFO - functools32 == 3.2.3-2 10:45:12 INFO - jsonschema == 2.5.1 10:45:12 INFO - manifestparser == 1.1 10:45:12 INFO - mozInstall == 1.12 10:45:12 INFO - mozcrash == 1.0 10:45:12 INFO - mozdebug == 0.1 10:45:12 INFO - mozdevice == 0.49 10:45:12 INFO - mozfile == 1.2 10:45:12 INFO - mozhttpd == 0.7 10:45:12 INFO - mozinfo == 0.9 10:45:12 INFO - mozleak == 0.1 10:45:12 INFO - mozlog == 3.4 10:45:12 INFO - moznetwork == 0.27 10:45:12 INFO - mozprocess == 0.25 10:45:12 INFO - mozprofile == 0.28 10:45:12 INFO - mozrunner == 6.13 10:45:12 INFO - mozscreenshot == 0.1 10:45:12 INFO - mozsystemmonitor == 0.3 10:45:12 INFO - moztest == 0.8 10:45:12 INFO - mozversion == 1.4 10:45:12 INFO - psutil == 3.1.1 10:45:12 INFO - requests == 1.2.3 10:45:12 INFO - wsgiref == 0.1.2 10:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:12 INFO - 'HOME': '/Users/cltbld', 10:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:12 INFO - 'LOGNAME': 'cltbld', 10:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:12 INFO - 'MOZ_NO_REMOTE': '1', 10:45:12 INFO - 'NO_EM_RESTART': '1', 10:45:12 INFO - 'PAGER': '/bin/cat', 10:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:12 INFO - 'PWD': '/builds/slave/test', 10:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:12 INFO - 'SHELL': '/bin/bash', 10:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:45:12 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:12 INFO - 'USER': 'cltbld', 10:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:12 INFO - 'XPC_FLAGS': '0x0', 10:45:12 INFO - 'XPC_SERVICE_NAME': '0', 10:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 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)) 10:45:12 INFO - Cleaning up... 10:45:12 INFO - Return code: 0 10:45:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:45:12 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 0x10b561b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b564768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feae04dcbe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, '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.XGKmZwHvY7/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.F34jJytbyA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 10:45:12 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 10:45:12 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 10:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:12 INFO - 'HOME': '/Users/cltbld', 10:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:12 INFO - 'LOGNAME': 'cltbld', 10:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:12 INFO - 'MOZ_NO_REMOTE': '1', 10:45:12 INFO - 'NO_EM_RESTART': '1', 10:45:12 INFO - 'PAGER': '/bin/cat', 10:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:12 INFO - 'PWD': '/builds/slave/test', 10:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:12 INFO - 'SHELL': '/bin/bash', 10:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:45:12 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:12 INFO - 'USER': 'cltbld', 10:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:12 INFO - 'XPC_FLAGS': '0x0', 10:45:12 INFO - 'XPC_SERVICE_NAME': '0', 10:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:45:13 INFO - Cleaning up... 10:45:13 INFO - Return code: 0 10:45:13 INFO - Running post-action listener: _resource_record_post_action 10:45:13 INFO - Running post-action listener: _start_resource_monitoring 10:45:13 INFO - Starting resource monitoring. 10:45:13 INFO - [mozharness: 2017-03-28 17:45:13.097771Z] Finished create-virtualenv step (success) 10:45:13 INFO - [mozharness: 2017-03-28 17:45:13.098727Z] Running install step. 10:45:13 INFO - Running pre-action listener: _resource_record_pre_action 10:45:13 INFO - Running main action method: install 10:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:13 INFO - Reading from file tmpfile_stdout 10:45:13 INFO - Detecting whether we're running mozinstall >=1.0... 10:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:45:13 INFO - Reading from file tmpfile_stdout 10:45:13 INFO - Output received: 10:45:13 INFO - Usage: mozinstall [options] installer 10:45:13 INFO - Options: 10:45:13 INFO - -h, --help show this help message and exit 10:45:13 INFO - -d DEST, --destination=DEST 10:45:13 INFO - Directory to install application into. [default: 10:45:13 INFO - "/builds/slave/test"] 10:45:13 INFO - --app=APP Application being installed. [default: firefox] 10:45:13 INFO - mkdir: /builds/slave/test/build/application 10:45:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:45:33 INFO - Reading from file tmpfile_stdout 10:45:33 INFO - Output received: 10:45:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:45:33 INFO - Running post-action listener: _resource_record_post_action 10:45:33 INFO - [mozharness: 2017-03-28 17:45:33.966657Z] Finished install step (success) 10:45:33 INFO - [mozharness: 2017-03-28 17:45:33.966814Z] Running run-tests step. 10:45:33 INFO - Running pre-action listener: _resource_record_pre_action 10:45:33 INFO - Running main action method: run_tests 10:45:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:45:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:45:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:45:33 INFO - 2017-03-28 10:45:33.980 screenresolution[1621:7329] starting screenresolution argv=screenresolution get 10:45:33 INFO - 2017-03-28 10:45:33.983 screenresolution[1621:7329] Display 0: 1600x1200x32@60 10:45:33 INFO - 2017-03-28 10:45:33.989 screenresolution[1622:7331] starting screenresolution argv=screenresolution list 10:45:33 INFO - Available Modes on Display 0 10:45:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:45:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:45:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:45:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:45:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:45:34 INFO - Intel Iris: 10:45:34 INFO - Chipset Model: Intel Iris 10:45:34 INFO - Type: GPU 10:45:34 INFO - Bus: Built-In 10:45:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:45:34 INFO - Vendor: Intel (0x8086) 10:45:34 INFO - Device ID: 0x0a2e 10:45:34 INFO - Revision ID: 0x0009 10:45:34 INFO - Displays: 10:45:34 INFO - Display: 10:45:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:45:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:45:34 INFO - Display Serial Number: 1600x1200 60 10:45:34 INFO - Main Display: Yes 10:45:34 INFO - Mirror: Off 10:45:34 INFO - Online: Yes 10:45:34 INFO - Rotation: Supported 10:45:34 INFO - Return code: 0 10:45:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:45:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:45:34 INFO - Python 2.7.3 10:45:34 INFO - Return code: 0 10:45:34 INFO - grabbing minidump binary from tooltool 10:45:34 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')]} 10:45:34 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 0x10b33fd30>, '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 0x10b266db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b334be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:45:34 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 10:45:34 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 10:45:34 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 10:45:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:45:34 INFO - Return code: 0 10:45:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:45:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:45:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:45:34 INFO - ENV: RUST_BACKTRACE is now 1 10:45:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:45:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:45:34 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/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--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 10:45:34 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/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --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 10:45:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XGKmZwHvY7/Render', 10:45:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:34 INFO - 'HOME': '/Users/cltbld', 10:45:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:34 INFO - 'LOGNAME': 'cltbld', 10:45:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:45:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:34 INFO - 'MOZ_NO_REMOTE': '1', 10:45:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:45:34 INFO - 'NO_EM_RESTART': '1', 10:45:34 INFO - 'PAGER': '/bin/cat', 10:45:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:34 INFO - 'PWD': '/builds/slave/test', 10:45:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:45:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:34 INFO - 'RUST_BACKTRACE': '1', 10:45:34 INFO - 'SHELL': '/bin/bash', 10:45:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F34jJytbyA/Listeners', 10:45:34 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 10:45:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:34 INFO - 'USER': 'cltbld', 10:45:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:34 INFO - 'XPC_FLAGS': '0x0', 10:45:34 INFO - 'XPC_SERVICE_NAME': '0', 10:45:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:34 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/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--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 10:45:34 INFO - mozversion application_buildid: 20170328095322 10:45:34 INFO - mozversion application_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 10:45:34 INFO - mozversion application_display_name: Nightly 10:45:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:45:34 INFO - mozversion application_name: Firefox 10:45:34 INFO - mozversion application_remotingname: firefox 10:45:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:45:34 INFO - mozversion application_vendor: Mozilla 10:45:34 INFO - mozversion application_version: 55.0a1 10:45:34 INFO - mozversion platform_buildid: 20170328095322 10:45:34 INFO - mozversion platform_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 10:45:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:45:34 INFO - mozversion platform_version: 55.0a1 10:45:34 INFO - using testdate: 1490723134 10:45:34 INFO - actual date: 1490723134 10:45:34 INFO - starting webserver on 'localhost:49236' 10:45:34 INFO - SUITE-START | Running 2 tests 10:45:34 INFO - TEST-START | dromaeo_css 10:45:34 INFO - operating with platform_type : mac_ 10:45:34 INFO - Initialising browser for dromaeo_css test... 10:45:34 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpZdAxgr/profile http://localhost:49236/getInfo.html) 10:45:37 INFO - PID 1632 | __metrics Screen width/height:1600/1200 10:45:37 INFO - PID 1632 | colorDepth:24 10:45:37 INFO - PID 1632 | Browser inner width/height: 1024/689 10:45:37 INFO - PID 1632 | __metrics 10:45:37 ERROR - PID 1632 | 1490723137487 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 10:45:37 INFO - PID 1632 | 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" 10:45:37 INFO - PID 1632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:45:38 INFO - TEST-INFO | 1632: exit 0 10:45:38 INFO - Browser initialized. 10:45:38 INFO - Running cycle 1/1 for dromaeo_css test... 10:45:38 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpZdAxgr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:45:50 INFO - PID 1633 | RSS: Main: 233771008 10:45:50 INFO - PID 1633 | 10:48:57 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 10:48:58 INFO - PID 1633 | RSS: Main: 358539264 10:48:58 INFO - PID 1633 | 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:50:40 INFO - PID 1633 | 10:50:40 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:40 INFO - PID 1633 | Full stack: 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:50:40 INFO - PID 1633 | 10:50:40 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:40 INFO - PID 1633 | Full stack: 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:50:40 INFO - PID 1633 | 10:50:40 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:40 INFO - PID 1633 | Full stack: 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | ************************* 10:50:40 INFO - PID 1633 | A coding exception was thrown and uncaught in a Task. 10:50:40 INFO - PID 1633 | 10:50:40 ERROR - PID 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:50:40 INFO - PID 1633 | Full stack: 10:50:40 INFO - PID 1633 | ************************* 10:52:01 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 10:52:01 INFO - PID 1633 | RSS: Main: 370900992 10:52:01 INFO - PID 1633 | 10:55:04 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 10:55:05 INFO - PID 1633 | RSS: Main: 343355392 10:55:05 INFO - PID 1633 | 10:58:07 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 10:58:08 INFO - PID 1633 | RSS: Main: 356913152 10:58:08 INFO - PID 1633 | 11:01:12 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 11:01:12 INFO - PID 1633 | RSS: Main: 334032896 11:01:12 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 11:04:16 INFO - PID 1633 | RSS: Main: 382685184 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | __start_tp_report 11:04:16 INFO - PID 1633 | _x_x_mozilla_page_load 11:04:16 INFO - PID 1633 | _x_x_mozilla_page_load_details 11:04:16 INFO - PID 1633 | |i|pagename|runs| 11:04:16 INFO - PID 1633 | |0;dojo.html;3191.2341038150844;3485.6165821759587;3485.9302813943723;3517.2789578136476;3550.538430004099;5947.494462970652;6114.785982490614;6216.316205217428;6230.781922632709;6256.593321434109;6108;6143.938560614392;6148.907766383497;6180.41286077823;6188.938110618892;5305.071612467803;5474.671519708811;5484.643498172626;5498.532624726883;5538.47384498474;5570.582206334541;5585.3297604287545;5591.888162236753;5592.664440133605;5671.262735844334;5385.165299378603;5430.15832545956;5430.59270554706;5458.590605704549;5521.089020311644;4545.545445455461;5472.1244600864065;5492.203630473599;5547.639403438763;5555.2778138842095;5403.8108666196495;5439;5454.672719636835;5479.589030822704;5490.670559766387;5905.822825315247;5994.610350327258;6140.416660417253;6149.5080393568405;6150.323464418955;6296.811095667092;6307.24313082433;6322.968385158045;6332.398422149889;6339;5764.135379693038;5928.911066334009;6371.33101024395;6404.967975160094;6556.27880933097;6161.4146656067605;6191.19514463117;6247.562670613013;6258.186435763367;6261.373862613702;6628.83427914306;6671.532992690562;6697.8995315070315;6708;6719.529632925746;5223.660462069954;6292.653904035322;6337.904931426032;6348.619082855043;6378;6536.575122617015;6547.639879806561;6559.770408035696;6568.638724870082;6573.342665733484;5392.164214546751;6124.755009799558;6426.807195784134;6438.7102580384;6439.5170362223025;5062.620303477254;6260.593061450991;6497.935020649733;6513.12072870166;6532.542722009509;5187;5193.298904647824;5198.714070726146;5202.687838729688;5214.217867319857;4593.517680643485;4678.461976872635;4701.835435759732;4708.976455117702;4722.504137065627;5187.740612969336;6188.752449901954;6271.592346497463;6288.968555157195;6293;6553.246376666649;6565.835854103686;6566.4418524425955;6575.539712220098;6583.440407565315;6259;6299.118123462719;6302.558820882586;6312.652804095819;6314.400131987454;5646.153077038477;5700.116481945305;5710.685912274865;5747.8563035924435;5805.36141024487;6757.831054223684;6762.323767623198;6784.118064651564;6802.489813263926;6869.622170780655;6552.475801935739;6556.868862622816;6559.639219842955;6585;6597.2742998271115;6486.643234622141;6576.76981305652;6577.671116444254;6786.4910131739325;6794.218664853407;6347.777827776005;6409.455196308366;6422.229332480132;6448.935510644834;6449.903251451138;6425.967870160806;6438.5171112165835;6489.707963141575;6500.447461965786;6501.1548498694865;6238.251409830667;6292.339304372975;6340.048992651139;6346.111544383698;6350.872982540416;5298.602604804732;6286.371362863677;6301.6534090625455;6307.117003619405;6320.683965801783;6166.722497487534;6225.62646241227;6279.686015699288;6292.087647291026;6310.2743184534365;6270.341614130451;6285.779997700059;6293.496520278275;6300.684965751786;6359.491240700825;5617.410171931889;5952.255968003999;6283.308836027942;6293.748250069946;6298.275698294571 11:04:16 INFO - PID 1633 | |1;ext.html;765.6286700950042;787.9251471110246;811.290121143999;1132.7451323452224;1156.780211759765;27655.17034488966;28361.723722432514;28375.858120709392;36375.818120909425;37342.626573734255;38875.41686874692;39213;39242;39279.41080883789;39379.606203937954;8409.453385529936;8607.268382187522;8609.61256743445;8622.741317760478;8627.051024387312;6060.787872424466;6208.78269260576;8333.583320833965;8353.081161072278;8568.700095496659;8515.872261916076;8622.741317760478;8623.31013518917;8635.352348573851;8638.308935285193;8559.828803423929;8605.39762216645;8635.352348573819;8638.65445382184;8642.999999999969;6945;8465.195806398382;8474.576271186415;8476.703315383993;8563.486190828571;38535;38796.806015970025;38875.61124388777;38924.61075389238;39076.609233907584;3021.743151832097;3022.667506574275;3043.4978228592254;3044.4672182368;3056.6484854241826;23908.40228994272;27840.025599103934;27878.721212787816;28226.858865705744;28603.856980715173;2759.448110377915;2829.2502486841004;2842.5025620516326;2848.9715102848913;2857.7713782897317;314632;320645.39677301695;320933;321514;321916;46148.61554153381;48816.7559162209;48846.51153488491;48854.511454884996;48870.75564622154;51667.7416612922;51675;51732.741336293075;51768.741156293974;51795.482045179066;14129.858701412853;14294.928525357307;14329.92835035839;14468.565943021516;14554.417823287162;11819.940900295443;11821.822672659917;11822.995045421063;11826.645200643994;11832;11454.193722538032;11641;13167.802482962763;13434.664133396547;13476.258805765778;5007.874803129878;5673.943260567424;5880.676562789002;5922.851928701817;5927.288725352899;845.7631863078348;914.1452741686544;961.3895176562933;989.7674046599044;1049.5277125293649;18691.971941543074;23191;24190.75809241885;24271.39321516941;24348.634770478457;1081.8918108189275;1084.810158222308;1100.290312748273;1123.4944275076248;1140.8745038045809;12281.447334869952;12293.56972505958;12359.75280494403;12386.938065309616;12420.192687475284;474234.6288268606;474381;476342;477196;478871;55287.72356138274;55463;55712.44287557073;55804.44195558074;55843.72078139583;1443.682389874247;1474.9410023598937;1523.116592376441;1524.923753812305;1525.2907398059956;23655.881720591286;23698.644520332553;23775.64336534988;23779.762202378446;23788.762112378656;38898.61101389063;38922.805385974025;39001;39006.4149037759;39045.6095439042;423674;424554.7544524639;424612;424631;424887.8755606202;32563.511547326336;33661.49507757337;33707.662923371434;33712.831435842665;33740.987770366926;41311.38032929449;42483;43501.78249108734;43630.69107926767;43970.12059758723;24964.126255580966;26136.8693156533;28845.452228280767;29619.259518512208;30725.846370768002;23612.29163125109;28919.132426027252;29502.114936551938;29947.850260748557;30412.087637370914;35186.47220291749;35947.82026089853;36064.27871442609;36097;36158.27683446264;29361.706382935896;29743.851280743456;29895;30646.387072257985;30793.582845110475;22078.116875324806;22262.888685556467;23329.416764581023;23548;24044.75955240425 11:04:16 INFO - PID 1633 | |2;jquery.html;358.8582509908586;391.6983922379766;415.21524319037024;432.5523083608465;436.21045906908506;12087.68796852227;17678.734818977726;17815.821841781577;20653.070611822466;20967.895160524215;20118.497037574067;22184.667229991523;22206.1117555298;22287.331380058582;22357.329280121623;3618.294432585634;3707.443883417482;3815.2369526094753;5792.131180322954;5952.077427998667;5730.455606717355;5902.852428689274;5908.350081491032;5910.556708246877;5912.8226153215255;5757.395473475267;5862.208601838761;5904.498117660004;5906.320773111082;5994.250718660168;6017.759289628431;6023.759049638009;6052.878942421171;6061.605995610271;6065.302490213638;5098.107831129538;5248.7900483980975;5307.124324486455;5310.097283461821;5349.43830897754;23845.403864903343;25278.58739070239;25358.492830143296;26786.330341741417;27089.593656095174;2038.1847261095531;2058.4853786553363;2069.6791997240452;2069.865458745177;2095.863768855035;14510.564683059525;15242.314095865713;15705;15808.762868556982;15940.681186376207;1382.5437605590132;1696.778319609879;1702.4892532240278;1708.3081352052438;1734.9826501735074;125442;142549.2872535641;159150.40849591471;159438;162641.18679406645;9896.851547226797;11621.302721836717;11672.824907626393;11716.70708232301;11785.587504437475;15611.687766244611;15634.765478517831;15640.92179539095;15665;15680.980736252335;7521.323080922743;8007.519548827089;8615.91384086151;8630.266427353745;8816.603252853636;7156.248593897566;8200.42597018215;8201.630926608317;8376.204260595234;8394.664213431395;6047.365026672224;7062.081929349151;7358.742444014434;7361.153467351216;7389.298016688406;2084.8436367272516;2085.259732794836;2094.3612198279366;2105.631514484959;2262.4457008033137;849.2356878809121;884.1688812516214;885.2165833237502;911.6717981526646;932.398602901125;19221.423357299303;19721.901390492956;19754;19955.20179192845;20209.898950505154;939.628846605587;952.79991201847;959.4627008875052;963.6579014449909;964.6141543382703;10052.59263703082;10388.480575971322;11710.648680539427;12913.741725165632;12939.482420703067;688090;691671.5416422886;701765;703993;718382;127805.36097319453;158125;161962;163368.18315908345;165037;1174.6065068202076;1188.1623455463878;1230.44014973187;1231.950721971111;1232.4022848918266;20454.897725511277;20608.632698405014;20789.376318710463;21013.89493052525;21027;52281.99999999848;59557;62958.37041629708;64164;64443.355566445614;66354.66822665856;87272.25455490981;88963;89057;90353;8224.588770561328;8401.15988401155;8416.410851240355;8426.157384261525;8633.95683021581;13308.667283317607;13332.066755327034;13416.396262168031;13575.660608484866;13586.660333491742;10733.248672592843;10964.177686673691;11172.385518796544;11511.884881151416;11637.59268425635;8205.958970205349;10309.536070876678;10499.580016799242;10669.093127084285;10860.185486088732;11467.770644587228;11649.708757281136;11793.646190614296;11801.645950621494;11919.82120268214;8128.512289262427;8156.225158609923;8163.3469322452875;8238.217369349903;8477;11256.268342557707;11394.658160255205;11452.541898323974;11748.177627565989;11871.287722736319 11:04:16 INFO - PID 1633 | |3;mootools.html;1207.2877002568487;1208.2689972566598;1416.9443764395528;1417.8582141785823;1418.1632836626395;10212.438315892632;10627.202959778027;10641.201909856753;10647.84028239577;10649.84025239622;10266.435346055963;10557.41934193621;10598.682039538808;10599.0990765785;10626.46867656616;2395.0778950104204;2450.1791899713617;2463.1994601754413;2464.2484042367073;2469.2221950085664;2054.3939537836372;2353.5528249632603;2442.1818690738623;2479.132303693702;2485.63958226058;2103.4215590712524;2136.134865379524;2385.2128797496785;2466.260121963413;2469.4814089041324;2412.819038572114;2413.095089341497;2423.369923819802;2425.6604075429454;2428.611422172453;2386.72552656445;2419.649150873131;2421.1767998880464;2435.768601982809;2441.8779061047053;10025.147862431702;10223.539940702613;10253.43606101664;10258.589656413735;10300.381977081324;6030.155778191057;6279.686015699197;6282.183316168915;6299.433051025384;6304.27500837405;3722.5346831646043;3804.619538046173;3839.404892241707;3966.464527288837;4061.776602286873;1124.1400328748432;1158.536585365852;1195.432169719385;1210.1831263896834;1214.9453274602663;12908.290044047573;14526;14835.629109272246;14837.25813709321;14849.886258530654;5284.735763211824;5633.69014704187;5637.802676906289;5666.943330566724;5748.281464816898;12474.251544907334;12506.874931250753;12507.624771256877;12512.311822849648;12524;6288.434040936338;6291.307956124822;6297.023961286008;6299.0866324382705;6303.117563541108;5518.641288316247;5663.490285874209;5667.546596272288;5730.7421166047625;5745.511631511368;4321.7406955582765;4466.2630665940405;4469.821207151677;4470.463544374631;4471.575200355961;1366.685662297677;1411.7458857405766;1415.5116484812722;1434.1323499282992;1440.985590144085;561.5866801131093;659.581165959619;743.2604558464362;757.814335487809;803.7628899474552;4383.802728877208;6271.310155882849;6462.063000864849;6527.652760302906;6615.437687796495;558.9860253493695;559.2841163310968;586.302300262686;592.7747455966705;695.5339922252103;3909.179072394772;3950.6641935435237;4077.55146933837;4127.628513433805;4134.193832202692;10880.945595271973;12580.622581322576;13167.407466664023;13286.269255191057;13313.93343033298;13185.736285274432;13189.802152967713;13202.207867527979;13203.471861125447;13227.93386033083;9388.295877809054;9794.363366381162;9819.36174148678;9821.459819709842;9848.310618256653;6484.351564843478;6530;6542;6744.393004629607;6839.863202735818;12017.579384721494;12226.633201003984;12341;12349.073819463396;12393.504259829866;12099.939500302442;12449.564265250672;12459.813102803284;12465.189762665761;12471.93764031174;8620.482771033758;8976.28189744832;8979.910200897908;8982.461052336881;8983;9618.278629102706;9625.663101791404;9697.272704547328;9712.805743884941;9712.980137085788;8197.385196110436;8279;8338.291245244221;8382.119877412784;8425.283850872744;7994.84010319802;8096.068952070352;8162.591870406337;8223.21879421454;8307.335413167053;7482.476226664299;7633.389328853789;7646.847063058819;7650.693972241048;7661.348785353084;4990.451050384528;5726.398728133652;5866.120081987736;5891.440313170242;6029.758809647565;5750.30996280449;5858.121281807763;5950.851228719317;5994.3705910878725;6033.687925684414 11:04:16 INFO - PID 1633 | |4;prototype.html;382.58680624925086;392.06296950288817;406.4472317647998;406.68888300450163;409.4533797380497;4616.746078965655;6242.750289988407;6282;6285.874282514353;6333.8099857004245;5360.544353729928;5921.970390148043;6038.516918646509;6111;6152.661603611799;3825.9617403826023;4000.3198656564314;4523;4678.7894544745395;4679.625629949596;3900.9520001998894;4051.6556092732008;4641.0949864776385;4657.650676199281;4678.508756580561;3502.7932402586334;3832.2910261601555;4439.644828413735;4583.610393116589;4608.769561521927;3908.5700572936953;4280.737182531129;4578.381918441001;4592.977035114836;4598.9080218395775;3328.5839270091237;3438.8995521433153;3680.043188770912;3942.38892971587;3972.4239985201984;5397.595180361449;5926.8814623707285;6030.517558595346;6040.154378387029;6080.391960803884;4791.233402655594;5162.967406518711;5376.91934620981;5379.596530260246;5412.83761487156;4963.875903102451;5726;5766.596338256324;6591.7363305467725;6633.30350313215;4255.8084886180195;4365.493904602907;4412.955870441287;4414.536473670282;4467.30756732707;5384.488473595002;5911.704414779244;6789.287124851919;6850.314968503159;7021.1574611047;4227.513835908849;4430.623397011225;4468.218061839165;4503.144402563503;4504.594586487232;3874.748141370802;4310.202612516695;4561.475430325489;4561.657875659339;4563.589276965089;4802.351682522814;4946.406431228275;5236.24074509194;5239.397469291004;5370.3287089113865;6585.736570537125;6586.538942274091;6588.8023359299295;6600.580875111874;6623.701933413008;1471.1761413608413;1534.9155796431303;1535.8464153584775;1554.4481708993371;1555.4478160253173;996.8405055191134;1006.5269323417982;1018.3024628129755;1027.6403258859339;1027.7841653252901;6198.318184999646;6210.285817130997;6210.875782484325;6235.719392627343;6251.78118765841;5784.132380143013;6775.322467753185;6832.968548556376;6833.555818871758;6840.863182736417;4849.733264670406;5442.755076021588;5744.827655170351;6130.662813545208;6198.349173336825;2968.85155742212;3270.9836450817593;3532.434810430319;3545.2554963457965;3551.5261166615564;6060.272987070092;6929.965350173317;6971.860562788817;6987.720491180397;6987.930120698829;5896.292444906553;6722.831929201809;6959.304069593;6975.6163411012885;6989.370956613828;6603.9009414859775;6891.896621550581;6995.8950615740805;7024.789256322319;7049.647517624201;6693.698783554654;6697.837989050183;6714.261431242657;6715.93284067153;6728.461723062045;5836.708164591669;6031.73809821317;6174.753009879554;6378.712957916998;6688.297728738413;5112.3865136183895;5812;6918.578497526027;6957.234704182435;7019.754308599175;4069.7354671946227;4397.846075387346;4444.533324001062;4460.620847228022;4473.955260447354;5642.492175704207;6573.5069869759;6601.669916504252;6719.596824190564;6824.658767061726;5460.34475862899;6391.392817682313;6426.260979987174;6448.838779030374;6459.967700161657;5510.504054635102;5956.702164891824;6432.195975503062;6472.288048314587;6475.222973243241;5093.567046801063;6271.874562508816;6358.728254349056;6366.9681651591445;6404.455621272244;5984.431479009487;6449.161608990801;6461.773837915838;6477.190351206099;6478.343879009583;4754.904901902012;6054.515638748978;6465.224173099258;6468.741250349934;6470.223573171062 11:04:16 INFO - PID 1633 | |5;yui.html;712.1881055596624;717.4690728860638;723.7901008707473;731.9304666056722;762.1387831750125;14558.272086395678;15476.535703928887;15570.688586228283;17074.573135671613;18106.728399074025;14609.780853287182;17013.319467221296;17142;17541.21064552095;17610.207540660667;941.5292353823088;951.6003278622995;954.179405711088;955.2358113509199;956.550421301987;931.1014870649822;965.3435663748648;969.937917979811;974.9366291191085;976.7997560500116;1007.566746299091;1007.7984403119369;1010.8584798128268;1010.8736407949007;1010.9797804043915;1002.5839276700161;1004.3120462483222;1004.8542961270612;1004.9949750251275;1008.6721815409948;745.4967896669726;748.5658318175446;749.7788152495027;750.1373420566392;750.5684231566817;15733.44932927342;16076.356945722157;16263.268152933146;16274.267657955303;16544.255508502025;14605.269736513132;14660.774888285101;14700.264986750726;14727;14868;9002.324825638038;10628.574857005711;10741.71099468061;11501.194916355858;11559.94220028903;2954.23189970607;3047.3143542702937;3078.615173103362;3121.9687803122133;3125.546795714609;104628.47685761523;105244;105474;106971;108103;9571.664991725256;9757.853632195523;11163.386013769321;11201.495932683049;11312.660620181408;22589;23752;23762.287131386085;24835.379115521897;24968.875155624468;7513.812154696177;7783.532988020737;7960.562169080757;8016.198380162054;8240.381971352173;4086.9795651021554;4140.648044916215;4180.623743863067;4207.074443622399;4231.619154276068;4163.2298024865495;4169.10364271677;4196.748195108242;4235.830566777356;4249.298865687127;771.7607541662103;780.478067767855;782.8708263136633;787.189195129018;803.0604193094094;534.5109225059106;566.4448840136689;567.1528924797493;568.5196009372074;585.783260193731;10116.74708132288;10191.337563058378;10305.731410469307;10447.030617851378;10651.946740266249;545.5008667069587;574.827551734478;586.6099044135602;586.6714639801725;597.1311741416229;4618.630509559227;4734.5339551904335;4765.072022078159;4793.448753393334;4825.276208568743;107101;107668;109469;110284;111467;86784.56607717047;93724.53137734268;94504.05495944952;95659.56510652346;95891.0410895896;82860;84281;84741.57629211938;84905.57547212225;86645;7723.806904827199;9320.207782338306;9547.188488978514;9555.761105972406;9569.904300956901;39298.80350598229;39624.92558047277;40500.79749601233;41117.79441102775;41212.793936031325;39428.605713942496;51673.708157296365;52270.73864630653;52567.73716131395;53283.93432131413;11492.253003554742;11669.824952625548;11682.4742886572;12095.153339266168;12303.138780285293;12362.814557781461;12626.625386996904;12696.555620553236;12717;12901.419436125585;11583.884161158281;11743.823842642196;11897.643070707893;12203.413556237632;12216.620943061704;11704.590339338083;11808.394726057017;11948.283103013848;11987.280763154238;12242.15313650385;10049.748756281151;11652.766944660889;11895.821562676394;11929.46317415701;12014.519419223134;6685.498587605852;7868.488548244346;7949.7217597383815;7970.362371010421;8132.552709600792;7969.203079691984;8740.388172827841;8786.868196976922;8849.53982592865;9148.817023659356 11:04:16 INFO - PID 1633 | __end_tp_report 11:04:16 INFO - PID 1633 | __start_cc_report 11:04:16 INFO - PID 1633 | _x_x_mozilla_cycle_collect,1357 11:04:16 INFO - PID 1633 | __end_cc_report 11:04:16 INFO - PID 1633 | __startTimestamp1490724256504__endTimestamp 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | ------- Summary: start ------- 11:04:16 INFO - PID 1633 | Number of tests: 6 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:6000.73 Median:6271.59 stddev:686.95 (11.0%) stddev-sans-first:652.74 11:04:16 INFO - PID 1633 | Values: 3191.2 3485.6 3485.9 3517.3 3550.5 5947.5 6114.8 6216.3 6230.8 6256.6 6108.0 6143.9 6148.9 6180.4 6188.9 5305.1 5474.7 5484.6 5498.5 5538.5 5570.6 5585.3 5591.9 5592.7 5671.3 5385.2 5430.2 5430.6 5458.6 5521.1 4545.5 5472.1 5492.2 5547.6 5555.3 5403.8 5439.0 5454.7 5479.6 5490.7 5905.8 5994.6 6140.4 6149.5 6150.3 6296.8 6307.2 6323.0 6332.4 6339.0 5764.1 5928.9 6371.3 6405.0 6556.3 6161.4 6191.2 6247.6 6258.2 6261.4 6628.8 6671.5 6697.9 6708.0 6719.5 5223.7 6292.7 6337.9 6348.6 6378.0 6536.6 6547.6 6559.8 6568.6 6573.3 5392.2 6124.8 6426.8 6438.7 6439.5 5062.6 6260.6 6497.9 6513.1 6532.5 5187.0 5193.3 5198.7 5202.7 5214.2 4593.5 4678.5 4701.8 4709.0 4722.5 5187.7 6188.8 6271.6 6289.0 6293.0 6553.2 6565.8 6566.4 6575.5 6583.4 6259.0 6299.1 6302.6 6312.7 6314.4 5646.2 5700.1 5710.7 5747.9 5805.4 6757.8 6762.3 6784.1 6802.5 6869.6 6552.5 6556.9 6559.6 6585.0 6597.3 6486.6 6576.8 6577.7 6786.5 6794.2 6347.8 6409.5 6422.2 6448.9 6449.9 6426.0 6438.5 6489.7 6500.4 6501.2 6238.3 6292.3 6340.0 6346.1 6350.9 5298.6 6286.4 6301.7 6307.1 6320.7 6166.7 6225.6 6279.7 6292.1 6310.3 6270.3 6285.8 6293.5 6300.7 6359.5 5617.4 5952.3 6283.3 6293.7 6298.3 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:53599.51 Median:34463.73 stddev:109567.63 (317.9%) stddev-sans-first:109803.19 11:04:16 INFO - PID 1633 | Values: 765.6 787.9 811.3 1132.7 1156.8 27655.2 28361.7 28375.9 36375.8 37342.6 38875.4 39213.0 39242.0 39279.4 39379.6 8409.5 8607.3 8609.6 8622.7 8627.1 6060.8 6208.8 8333.6 8353.1 8568.7 8515.9 8622.7 8623.3 8635.4 8638.3 8559.8 8605.4 8635.4 8638.7 8643.0 6945.0 8465.2 8474.6 8476.7 8563.5 38535.0 38796.8 38875.6 38924.6 39076.6 3021.7 3022.7 3043.5 3044.5 3056.6 23908.4 27840.0 27878.7 28226.9 28603.9 2759.4 2829.3 2842.5 2849.0 2857.8 314632.0 320645.4 320933.0 321514.0 321916.0 46148.6 48816.8 48846.5 48854.5 48870.8 51667.7 51675.0 51732.7 51768.7 51795.5 14129.9 14294.9 14329.9 14468.6 14554.4 11819.9 11821.8 11823.0 11826.6 11832.0 11454.2 11641.0 13167.8 13434.7 13476.3 5007.9 5673.9 5880.7 5922.9 5927.3 845.8 914.1 961.4 989.8 1049.5 18692.0 23191.0 24190.8 24271.4 24348.6 1081.9 1084.8 1100.3 1123.5 1140.9 12281.4 12293.6 12359.8 12386.9 12420.2 474234.6 474381.0 476342.0 477196.0 478871.0 55287.7 55463.0 55712.4 55804.4 55843.7 1443.7 1474.9 1523.1 1524.9 1525.3 23655.9 23698.6 23775.6 23779.8 23788.8 38898.6 38922.8 39001.0 39006.4 39045.6 423674.0 424554.8 424612.0 424631.0 424887.9 32563.5 33661.5 33707.7 33712.8 33741.0 41311.4 42483.0 43501.8 43630.7 43970.1 24964.1 26136.9 28845.5 29619.3 30725.8 23612.3 28919.1 29502.1 29947.9 30412.1 35186.5 35947.8 36064.3 36097.0 36158.3 29361.7 29743.9 29895.0 30646.4 30793.6 22078.1 22262.9 23329.4 23548.0 24044.8 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:40165.07 Median:22322.33 stddev:117611.30 (526.9%) stddev-sans-first:117903.25 11:04:16 INFO - PID 1633 | Values: 358.9 391.7 415.2 432.6 436.2 12087.7 17678.7 17815.8 20653.1 20967.9 20118.5 22184.7 22206.1 22287.3 22357.3 3618.3 3707.4 3815.2 5792.1 5952.1 5730.5 5902.9 5908.4 5910.6 5912.8 5757.4 5862.2 5904.5 5906.3 5994.3 6017.8 6023.8 6052.9 6061.6 6065.3 5098.1 5248.8 5307.1 5310.1 5349.4 23845.4 25278.6 25358.5 26786.3 27089.6 2038.2 2058.5 2069.7 2069.9 2095.9 14510.6 15242.3 15705.0 15808.8 15940.7 1382.5 1696.8 1702.5 1708.3 1735.0 125442.0 142549.3 159150.4 159438.0 162641.2 9896.9 11621.3 11672.8 11716.7 11785.6 15611.7 15634.8 15640.9 15665.0 15681.0 7521.3 8007.5 8615.9 8630.3 8816.6 7156.2 8200.4 8201.6 8376.2 8394.7 6047.4 7062.1 7358.7 7361.2 7389.3 2084.8 2085.3 2094.4 2105.6 2262.4 849.2 884.2 885.2 911.7 932.4 19221.4 19721.9 19754.0 19955.2 20209.9 939.6 952.8 959.5 963.7 964.6 10052.6 10388.5 11710.6 12913.7 12939.5 688090.0 691671.5 701765.0 703993.0 718382.0 127805.4 158125.0 161962.0 163368.2 165037.0 1174.6 1188.2 1230.4 1232.0 1232.4 20454.9 20608.6 20789.4 21013.9 21027.0 52282.0 59557.0 62958.4 64164.0 64443.4 66354.7 87272.3 88963.0 89057.0 90353.0 8224.6 8401.2 8416.4 8426.2 8634.0 13308.7 13332.1 13416.4 13575.7 13586.7 10733.2 10964.2 11172.4 11511.9 11637.6 8206.0 10309.5 10499.6 10669.1 10860.2 11467.8 11649.7 11793.6 11801.6 11919.8 8128.5 8156.2 8163.3 8238.2 8477.0 11256.3 11394.7 11452.5 11748.2 11871.3 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:6632.16 Median:4014.12 stddev:4041.11 (100.7%) stddev-sans-first:4031.88 11:04:16 INFO - PID 1633 | Values: 1207.3 1208.3 1416.9 1417.9 1418.2 10212.4 10627.2 10641.2 10647.8 10649.8 10266.4 10557.4 10598.7 10599.1 10626.5 2395.1 2450.2 2463.2 2464.2 2469.2 2054.4 2353.6 2442.2 2479.1 2485.6 2103.4 2136.1 2385.2 2466.3 2469.5 2412.8 2413.1 2423.4 2425.7 2428.6 2386.7 2419.6 2421.2 2435.8 2441.9 10025.1 10223.5 10253.4 10258.6 10300.4 6030.2 6279.7 6282.2 6299.4 6304.3 3722.5 3804.6 3839.4 3966.5 4061.8 1124.1 1158.5 1195.4 1210.2 1214.9 12908.3 14526.0 14835.6 14837.3 14849.9 5284.7 5633.7 5637.8 5666.9 5748.3 12474.3 12506.9 12507.6 12512.3 12524.0 6288.4 6291.3 6297.0 6299.1 6303.1 5518.6 5663.5 5667.5 5730.7 5745.5 4321.7 4466.3 4469.8 4470.5 4471.6 1366.7 1411.7 1415.5 1434.1 1441.0 561.6 659.6 743.3 757.8 803.8 4383.8 6271.3 6462.1 6527.7 6615.4 559.0 559.3 586.3 592.8 695.5 3909.2 3950.7 4077.6 4127.6 4134.2 10880.9 12580.6 13167.4 13286.3 13313.9 13185.7 13189.8 13202.2 13203.5 13227.9 9388.3 9794.4 9819.4 9821.5 9848.3 6484.4 6530.0 6542.0 6744.4 6839.9 12017.6 12226.6 12341.0 12349.1 12393.5 12099.9 12449.6 12459.8 12465.2 12471.9 8620.5 8976.3 8979.9 8982.5 8983.0 9618.3 9625.7 9697.3 9712.8 9713.0 8197.4 8279.0 8338.3 8382.1 8425.3 7994.8 8096.1 8162.6 8223.2 8307.3 7482.5 7633.4 7646.8 7650.7 7661.3 4990.5 5726.4 5866.1 5891.4 6029.8 5750.3 5858.1 5950.9 5994.4 6033.7 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5208.21 Median:5798.07 stddev:1654.02 (28.5%) stddev-sans-first:1618.52 11:04:16 INFO - PID 1633 | Values: 382.6 392.1 406.4 406.7 409.5 4616.7 6242.8 6282.0 6285.9 6333.8 5360.5 5922.0 6038.5 6111.0 6152.7 3826.0 4000.3 4523.0 4678.8 4679.6 3901.0 4051.7 4641.1 4657.7 4678.5 3502.8 3832.3 4439.6 4583.6 4608.8 3908.6 4280.7 4578.4 4593.0 4598.9 3328.6 3438.9 3680.0 3942.4 3972.4 5397.6 5926.9 6030.5 6040.2 6080.4 4791.2 5163.0 5376.9 5379.6 5412.8 4963.9 5726.0 5766.6 6591.7 6633.3 4255.8 4365.5 4413.0 4414.5 4467.3 5384.5 5911.7 6789.3 6850.3 7021.2 4227.5 4430.6 4468.2 4503.1 4504.6 3874.7 4310.2 4561.5 4561.7 4563.6 4802.4 4946.4 5236.2 5239.4 5370.3 6585.7 6586.5 6588.8 6600.6 6623.7 1471.2 1534.9 1535.8 1554.4 1555.4 996.8 1006.5 1018.3 1027.6 1027.8 6198.3 6210.3 6210.9 6235.7 6251.8 5784.1 6775.3 6833.0 6833.6 6840.9 4849.7 5442.8 5744.8 6130.7 6198.3 2968.9 3271.0 3532.4 3545.3 3551.5 6060.3 6930.0 6971.9 6987.7 6987.9 5896.3 6722.8 6959.3 6975.6 6989.4 6603.9 6891.9 6995.9 7024.8 7049.6 6693.7 6697.8 6714.3 6715.9 6728.5 5836.7 6031.7 6174.8 6378.7 6688.3 5112.4 5812.0 6918.6 6957.2 7019.8 4069.7 4397.8 4444.5 4460.6 4474.0 5642.5 6573.5 6601.7 6719.6 6824.7 5460.3 6391.4 6426.3 6448.8 6460.0 5510.5 5956.7 6432.2 6472.3 6475.2 5093.6 6271.9 6358.7 6367.0 6404.5 5984.4 6449.2 6461.8 6477.2 6478.3 4754.9 6054.5 6465.2 6468.7 6470.2 11:04:16 INFO - PID 1633 | 11:04:16 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:19994.27 Median:22293.89 stddev:29818.86 (133.8%) stddev-sans-first:29867.36 11:04:16 INFO - PID 1633 | Values: 712.2 717.5 723.8 731.9 762.1 14558.3 15476.5 15570.7 17074.6 18106.7 14609.8 17013.3 17142.0 17541.2 17610.2 941.5 951.6 954.2 955.2 956.6 931.1 965.3 969.9 974.9 976.8 1007.6 1007.8 1010.9 1010.9 1011.0 1002.6 1004.3 1004.9 1005.0 1008.7 745.5 748.6 749.8 750.1 750.6 15733.4 16076.4 16263.3 16274.3 16544.3 14605.3 14660.8 14700.3 14727.0 14868.0 9002.3 10628.6 10741.7 11501.2 11559.9 2954.2 3047.3 3078.6 3122.0 3125.5 104628.5 105244.0 105474.0 106971.0 108103.0 9571.7 9757.9 11163.4 11201.5 11312.7 22589.0 23752.0 23762.3 24835.4 24968.9 7513.8 7783.5 7960.6 8016.2 8240.4 4087.0 4140.6 4180.6 4207.1 4231.6 4163.2 4169.1 4196.7 4235.8 4249.3 771.8 780.5 782.9 787.2 803.1 534.5 566.4 567.2 568.5 585.8 10116.7 10191.3 10305.7 10447.0 10651.9 545.5 574.8 586.6 586.7 597.1 4618.6 4734.5 4765.1 4793.4 4825.3 107101.0 107668.0 109469.0 110284.0 111467.0 86784.6 93724.5 94504.1 95659.6 95891.0 82860.0 84281.0 84741.6 84905.6 86645.0 7723.8 9320.2 9547.2 9555.8 9569.9 39298.8 39624.9 40500.8 41117.8 41212.8 39428.6 51673.7 52270.7 52567.7 53283.9 11492.3 11669.8 11682.5 12095.2 12303.1 12362.8 12626.6 12696.6 12717.0 12901.4 11583.9 11743.8 11897.6 12203.4 12216.6 11704.6 11808.4 11948.3 11987.3 12242.2 10049.7 11652.8 11895.8 11929.5 12014.5 6685.5 7868.5 7949.7 7970.4 8132.6 7969.2 8740.4 8786.9 8849.5 9148.8 11:04:16 INFO - PID 1633 | -------- Summary: end -------- 11:04:16 INFO - PID 1633 | 11:04:17 INFO - TEST-INFO | 1633: exit 0 11:04:17 INFO - TEST-OK | dromaeo_css | took 1122617ms 11:04:17 INFO - TEST-START | kraken 11:04:17 INFO - operating with platform_type : mac_ 11:04:17 INFO - Initialising browser for kraken test... 11:04:17 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpqCq1oT/profile http://localhost:49236/getInfo.html) 11:04:19 INFO - PID 1643 | __metrics Screen width/height:1600/1200 11:04:19 INFO - PID 1643 | colorDepth:24 11:04:19 INFO - PID 1643 | Browser inner width/height: 1024/689 11:04:19 INFO - PID 1643 | __metrics 11:04:19 ERROR - PID 1643 | 1490724259558 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 11:04:19 INFO - PID 1643 | 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" 11:04:19 INFO - PID 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:04:20 INFO - TEST-INFO | 1643: exit 0 11:04:20 INFO - Browser initialized. 11:04:20 INFO - Running cycle 1/1 for kraken test... 11:04:20 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpqCq1oT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:04:32 INFO - PID 1644 | RSS: Main: 233762816 11:04:32 INFO - PID 1644 | 11:04:35 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 11:04:36 INFO - PID 1644 | RSS: Main: 286380032 11:04:36 INFO - PID 1644 | 11:04:39 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 11:04:39 INFO - PID 1644 | RSS: Main: 288329728 11:04:39 INFO - PID 1644 | 11:04:43 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 11:04:43 INFO - PID 1644 | RSS: Main: 378728448 11:04:43 INFO - PID 1644 | 11:04:46 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 11:04:46 INFO - PID 1644 | RSS: Main: 282517504 11:04:46 INFO - PID 1644 | 11:04:48 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:04:49 INFO - PID 1644 | RSS: Main: 284995584 11:04:49 INFO - PID 1644 | 11:04:52 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 11:04:53 INFO - PID 1644 | RSS: Main: 328060928 11:04:53 INFO - PID 1644 | 11:04:56 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 11:04:57 INFO - PID 1644 | RSS: Main: 332660736 11:04:57 INFO - PID 1644 | 11:05:00 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 11:05:01 INFO - PID 1644 | RSS: Main: 332693504 11:05:01 INFO - PID 1644 | 11:05:03 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:05:03 INFO - PID 1644 | RSS: Main: 304975872 11:05:03 INFO - PID 1644 | 11:05:05 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:05:06 INFO - PID 1644 | RSS: Main: 307552256 11:05:06 INFO - PID 1644 | 11:05:08 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:05:09 INFO - PID 1644 | RSS: Main: 310063104 11:05:09 INFO - PID 1644 | 11:05:12 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:05:13 INFO - PID 1644 | RSS: Main: 328024064 11:05:13 INFO - PID 1644 | 11:05:17 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:05:17 INFO - PID 1644 | RSS: Main: 309923840 11:05:17 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 11:05:19 INFO - PID 1644 | RSS: Main: 375894016 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | __start_tp_report 11:05:19 INFO - PID 1644 | _x_x_mozilla_page_load 11:05:19 INFO - PID 1644 | _x_x_mozilla_page_load_details 11:05:19 INFO - PID 1644 | |i|pagename|runs| 11:05:19 INFO - PID 1644 | |0;ai-astar;112;123;123;122;124;125;124;125;123;124 11:05:19 INFO - PID 1644 | |1;audio-beat-detection;158;121;119;166;157;123;123;119;142;120 11:05:19 INFO - PID 1644 | |2;audio-dft;215;210;214;210;216;212;215;216;217;211 11:05:19 INFO - PID 1644 | |3;audio-fft;86;63;65;102;68;64;63;64;85;64 11:05:19 INFO - PID 1644 | |4;audio-oscillator;101;75;98;77;75;75;92;76;73;93 11:05:19 INFO - PID 1644 | |5;imaging-gaussian-blur;140;133;95;94;110;137;94;93;93;95 11:05:19 INFO - PID 1644 | |6;imaging-darkroom;102;101;102;101;99;115;150;103;117;161 11:05:19 INFO - PID 1644 | |7;imaging-desaturate;100;102;103;164;157;100;104;102;102;101 11:05:19 INFO - PID 1644 | |8;json-parse-financial;66;64;64;65;65;65;64;73;96;65 11:05:19 INFO - PID 1644 | |9;json-stringify-tinderbox;60;67;71;69;64;64;63;63;64;64 11:05:19 INFO - PID 1644 | |10;stanford-crypto-aes;92;92;90;82;89;120;114;116;89;91 11:05:19 INFO - PID 1644 | |11;stanford-crypto-ccm;181;171;151;164;156;166;154;149;223;203 11:05:19 INFO - PID 1644 | |12;stanford-crypto-pbkdf2;206;203;204;201;203;208;230;258;265;201 11:05:19 INFO - PID 1644 | |13;stanford-crypto-sha256-iterative;78;75;76;75;75;75;74;77;78;76 11:05:19 INFO - PID 1644 | __end_tp_report 11:05:19 INFO - PID 1644 | __start_cc_report 11:05:19 INFO - PID 1644 | _x_x_mozilla_cycle_collect,2188 11:05:19 INFO - PID 1644 | __end_cc_report 11:05:19 INFO - PID 1644 | __startTimestamp1490724319818__endTimestamp 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | ------- Summary: start ------- 11:05:19 INFO - PID 1644 | Number of tests: 14 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#0] ai-astar Cycles:10 Average:122.50 Median:124.00 stddev:3.81 (3.1%) stddev-sans-first:1.00 11:05:19 INFO - PID 1644 | Values: 112.0 123.0 123.0 122.0 124.0 125.0 124.0 125.0 123.0 124.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:132.50 stddev:18.98 (14.3%) stddev-sans-first:18.19 11:05:19 INFO - PID 1644 | Values: 158.0 121.0 119.0 166.0 157.0 123.0 123.0 119.0 142.0 120.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#2] audio-dft Cycles:10 Average:213.60 Median:215.00 stddev:2.63 (1.2%) stddev-sans-first:2.74 11:05:19 INFO - PID 1644 | Values: 215.0 210.0 214.0 210.0 216.0 212.0 215.0 216.0 217.0 211.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#3] audio-fft Cycles:10 Average:72.40 Median:64.50 stddev:13.67 (21.2%) stddev-sans-first:13.59 11:05:19 INFO - PID 1644 | Values: 86.0 63.0 65.0 102.0 68.0 64.0 63.0 64.0 85.0 64.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:76.50 stddev:11.08 (14.5%) stddev-sans-first:9.77 11:05:19 INFO - PID 1644 | Values: 101.0 75.0 98.0 77.0 75.0 75.0 92.0 76.0 73.0 93.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:108.40 Median:93.50 stddev:20.20 (21.6%) stddev-sans-first:17.90 11:05:19 INFO - PID 1644 | Values: 140.0 133.0 95.0 94.0 110.0 137.0 94.0 93.0 93.0 95.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:116.00 stddev:22.30 (19.2%) stddev-sans-first:23.14 11:05:19 INFO - PID 1644 | Values: 102.0 101.0 102.0 101.0 99.0 115.0 150.0 103.0 117.0 161.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#7] imaging-desaturate Cycles:10 Average:113.50 Median:102.50 stddev:24.86 (24.3%) stddev-sans-first:25.88 11:05:19 INFO - PID 1644 | Values: 100.0 102.0 103.0 164.0 157.0 100.0 104.0 102.0 102.0 101.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:65.00 stddev:9.96 (15.3%) stddev-sans-first:10.51 11:05:19 INFO - PID 1644 | Values: 66.0 64.0 64.0 65.0 65.0 65.0 64.0 73.0 96.0 65.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:64.00 stddev:3.21 (5.0%) stddev-sans-first:2.88 11:05:19 INFO - PID 1644 | Values: 60.0 67.0 71.0 69.0 64.0 64.0 63.0 63.0 64.0 64.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:89.50 stddev:13.60 (15.2%) stddev-sans-first:14.28 11:05:19 INFO - PID 1644 | Values: 92.0 92.0 90.0 82.0 89.0 120.0 114.0 116.0 89.0 91.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:171.80 Median:168.50 stddev:24.25 (14.4%) stddev-sans-first:25.49 11:05:19 INFO - PID 1644 | Values: 181.0 171.0 151.0 164.0 156.0 166.0 154.0 149.0 223.0 203.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:217.90 Median:207.00 stddev:24.54 (11.9%) stddev-sans-first:25.65 11:05:19 INFO - PID 1644 | Values: 206.0 203.0 204.0 201.0 203.0 208.0 230.0 258.0 265.0 201.0 11:05:19 INFO - PID 1644 | 11:05:19 INFO - PID 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:76.00 stddev:1.37 (1.8%) stddev-sans-first:1.22 11:05:19 INFO - PID 1644 | Values: 78.0 75.0 76.0 75.0 75.0 75.0 74.0 77.0 78.0 76.0 11:05:19 INFO - PID 1644 | -------- Summary: end -------- 11:05:19 INFO - PID 1644 | 11:05:20 INFO - TEST-INFO | 1644: exit 0 11:05:20 INFO - TEST-OK | kraken | took 63664ms 11:05:20 INFO - SUITE-END | took 1186s 11:05:20 INFO - Completed test suite (00:19:46) 11:05:20 INFO - javascript benchmark 11:05:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7523.254178321617, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3191.2341038150844, 3485.6165821759587, 3485.9302813943723, 3517.2789578136476, 3550.538430004099, 5947.494462970652, 6114.785982490614, 6216.316205217428, 6230.781922632709, 6256.593321434109, 6108.0, 6143.938560614392, 6148.907766383497, 6180.41286077823, 6188.938110618892, 5305.071612467803, 5474.671519708811, 5484.643498172626, 5498.532624726883, 5538.47384498474, 5570.582206334541, 5585.3297604287545, 5591.888162236753, 5592.664440133605, 5671.262735844334, 5385.165299378603, 5430.15832545956, 5430.59270554706, 5458.590605704549, 5521.089020311644, 4545.545445455461, 5472.1244600864065, 5492.203630473599, 5547.639403438763, 5555.2778138842095, 5403.8108666196495, 5439.0, 5454.672719636835, 5479.589030822704, 5490.670559766387, 5905.822825315247, 5994.610350327258, 6140.416660417253, 6149.5080393568405, 6150.323464418955, 6296.811095667092, 6307.24313082433, 6322.968385158045, 6332.398422149889, 6339.0, 5764.135379693038, 5928.911066334009, 6371.33101024395, 6404.967975160094, 6556.27880933097, 6161.4146656067605, 6191.19514463117, 6247.562670613013, 6258.186435763367, 6261.373862613702, 6628.83427914306, 6671.532992690562, 6697.8995315070315, 6708.0, 6719.529632925746, 5223.660462069954, 6292.653904035322, 6337.904931426032, 6348.619082855043, 6378.0, 6536.575122617015, 6547.639879806561, 6559.770408035696, 6568.638724870082, 6573.342665733484, 5392.164214546751, 6124.755009799558, 6426.807195784134, 6438.7102580384, 6439.5170362223025, 5062.620303477254, 6260.593061450991, 6497.935020649733, 6513.12072870166, 6532.542722009509, 5187.0, 5193.298904647824, 5198.714070726146, 5202.687838729688, 5214.217867319857, 4593.517680643485, 4678.461976872635, 4701.835435759732, 4708.976455117702, 4722.504137065627, 5187.740612969336, 6188.752449901954, 6271.592346497463, 6288.968555157195, 6293.0, 6553.246376666649, 6565.835854103686, 6566.4418524425955, 6575.539712220098, 6583.440407565315, 6259.0, 6299.118123462719, 6302.558820882586, 6312.652804095819, 6314.400131987454, 5646.153077038477, 5700.116481945305, 5710.685912274865, 5747.8563035924435, 5805.36141024487, 6757.831054223684, 6762.323767623198, 6784.118064651564, 6802.489813263926, 6869.622170780655, 6552.475801935739, 6556.868862622816, 6559.639219842955, 6585.0, 6597.2742998271115, 6486.643234622141, 6576.76981305652, 6577.671116444254, 6786.4910131739325, 6794.218664853407, 6347.777827776005, 6409.455196308366, 6422.229332480132, 6448.935510644834, 6449.903251451138, 6425.967870160806, 6438.5171112165835, 6489.707963141575, 6500.447461965786, 6501.1548498694865, 6238.251409830667, 6292.339304372975, 6340.048992651139, 6346.111544383698, 6350.872982540416, 5298.602604804732, 6286.371362863677, 6301.6534090625455, 6307.117003619405, 6320.683965801783, 6166.722497487534, 6225.62646241227, 6279.686015699288, 6292.087647291026, 6310.2743184534365, 6270.341614130451, 6285.779997700059, 6293.496520278275, 6300.684965751786, 6359.491240700825, 5617.410171931889, 5952.255968003999, 6283.308836027942, 6293.748250069946, 6298.275698294571], "value": 5957.772306525528, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.6286700950042, 787.9251471110246, 811.290121143999, 1132.7451323452224, 1156.780211759765, 27655.17034488966, 28361.723722432514, 28375.858120709392, 36375.818120909425, 37342.626573734255, 38875.41686874692, 39213.0, 39242.0, 39279.41080883789, 39379.606203937954, 8409.453385529936, 8607.268382187522, 8609.61256743445, 8622.741317760478, 8627.051024387312, 6060.787872424466, 6208.78269260576, 8333.583320833965, 8353.081161072278, 8568.700095496659, 8515.872261916076, 8622.741317760478, 8623.31013518917, 8635.352348573851, 8638.308935285193, 8559.828803423929, 8605.39762216645, 8635.352348573819, 8638.65445382184, 8642.999999999969, 6945.0, 8465.195806398382, 8474.576271186415, 8476.703315383993, 8563.486190828571, 38535.0, 38796.806015970025, 38875.61124388777, 38924.61075389238, 39076.609233907584, 3021.743151832097, 3022.667506574275, 3043.4978228592254, 3044.4672182368, 3056.6484854241826, 23908.40228994272, 27840.025599103934, 27878.721212787816, 28226.858865705744, 28603.856980715173, 2759.448110377915, 2829.2502486841004, 2842.5025620516326, 2848.9715102848913, 2857.7713782897317, 314632.0, 320645.39677301695, 320933.0, 321514.0, 321916.0, 46148.61554153381, 48816.7559162209, 48846.51153488491, 48854.511454884996, 48870.75564622154, 51667.7416612922, 51675.0, 51732.741336293075, 51768.741156293974, 51795.482045179066, 14129.858701412853, 14294.928525357307, 14329.92835035839, 14468.565943021516, 14554.417823287162, 11819.940900295443, 11821.822672659917, 11822.995045421063, 11826.645200643994, 11832.0, 11454.193722538032, 11641.0, 13167.802482962763, 13434.664133396547, 13476.258805765778, 5007.874803129878, 5673.943260567424, 5880.676562789002, 5922.851928701817, 5927.288725352899, 845.7631863078348, 914.1452741686544, 961.3895176562933, 989.7674046599044, 1049.5277125293649, 18691.971941543074, 23191.0, 24190.75809241885, 24271.39321516941, 24348.634770478457, 1081.8918108189275, 1084.810158222308, 1100.290312748273, 1123.4944275076248, 1140.8745038045809, 12281.447334869952, 12293.56972505958, 12359.75280494403, 12386.938065309616, 12420.192687475284, 474234.6288268606, 474381.0, 476342.0, 477196.0, 478871.0, 55287.72356138274, 55463.0, 55712.44287557073, 55804.44195558074, 55843.72078139583, 1443.682389874247, 1474.9410023598937, 1523.116592376441, 1524.923753812305, 1525.2907398059956, 23655.881720591286, 23698.644520332553, 23775.64336534988, 23779.762202378446, 23788.762112378656, 38898.61101389063, 38922.805385974025, 39001.0, 39006.4149037759, 39045.6095439042, 423674.0, 424554.7544524639, 424612.0, 424631.0, 424887.8755606202, 32563.511547326336, 33661.49507757337, 33707.662923371434, 33712.831435842665, 33740.987770366926, 41311.38032929449, 42483.0, 43501.78249108734, 43630.69107926767, 43970.12059758723, 24964.126255580966, 26136.8693156533, 28845.452228280767, 29619.259518512208, 30725.846370768002, 23612.29163125109, 28919.132426027252, 29502.114936551938, 29947.850260748557, 30412.087637370914, 35186.47220291749, 35947.82026089853, 36064.27871442609, 36097.0, 36158.27683446264, 29361.706382935896, 29743.851280743456, 29895.0, 30646.387072257985, 30793.582845110475, 22078.116875324806, 22262.888685556467, 23329.416764581023, 23548.0, 24044.75955240425], "value": 17475.950621588392, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8582509908586, 391.6983922379766, 415.21524319037024, 432.5523083608465, 436.21045906908506, 12087.68796852227, 17678.734818977726, 17815.821841781577, 20653.070611822466, 20967.895160524215, 20118.497037574067, 22184.667229991523, 22206.1117555298, 22287.331380058582, 22357.329280121623, 3618.294432585634, 3707.443883417482, 3815.2369526094753, 5792.131180322954, 5952.077427998667, 5730.455606717355, 5902.852428689274, 5908.350081491032, 5910.556708246877, 5912.8226153215255, 5757.395473475267, 5862.208601838761, 5904.498117660004, 5906.320773111082, 5994.250718660168, 6017.759289628431, 6023.759049638009, 6052.878942421171, 6061.605995610271, 6065.302490213638, 5098.107831129538, 5248.7900483980975, 5307.124324486455, 5310.097283461821, 5349.43830897754, 23845.403864903343, 25278.58739070239, 25358.492830143296, 26786.330341741417, 27089.593656095174, 2038.1847261095531, 2058.4853786553363, 2069.6791997240452, 2069.865458745177, 2095.863768855035, 14510.564683059525, 15242.314095865713, 15705.0, 15808.762868556982, 15940.681186376207, 1382.5437605590132, 1696.778319609879, 1702.4892532240278, 1708.3081352052438, 1734.9826501735074, 125442.0, 142549.2872535641, 159150.40849591471, 159438.0, 162641.18679406645, 9896.851547226797, 11621.302721836717, 11672.824907626393, 11716.70708232301, 11785.587504437475, 15611.687766244611, 15634.765478517831, 15640.92179539095, 15665.0, 15680.980736252335, 7521.323080922743, 8007.519548827089, 8615.91384086151, 8630.266427353745, 8816.603252853636, 7156.248593897566, 8200.42597018215, 8201.630926608317, 8376.204260595234, 8394.664213431395, 6047.365026672224, 7062.081929349151, 7358.742444014434, 7361.153467351216, 7389.298016688406, 2084.8436367272516, 2085.259732794836, 2094.3612198279366, 2105.631514484959, 2262.4457008033137, 849.2356878809121, 884.1688812516214, 885.2165833237502, 911.6717981526646, 932.398602901125, 19221.423357299303, 19721.901390492956, 19754.0, 19955.20179192845, 20209.898950505154, 939.628846605587, 952.79991201847, 959.4627008875052, 963.6579014449909, 964.6141543382703, 10052.59263703082, 10388.480575971322, 11710.648680539427, 12913.741725165632, 12939.482420703067, 688090.0, 691671.5416422886, 701765.0, 703993.0, 718382.0, 127805.36097319453, 158125.0, 161962.0, 163368.18315908345, 165037.0, 1174.6065068202076, 1188.1623455463878, 1230.44014973187, 1231.950721971111, 1232.4022848918266, 20454.897725511277, 20608.632698405014, 20789.376318710463, 21013.89493052525, 21027.0, 52281.99999999848, 59557.0, 62958.37041629708, 64164.0, 64443.355566445614, 66354.66822665856, 87272.25455490981, 88963.0, 89057.0, 90353.0, 8224.588770561328, 8401.15988401155, 8416.410851240355, 8426.157384261525, 8633.95683021581, 13308.667283317607, 13332.066755327034, 13416.396262168031, 13575.660608484866, 13586.660333491742, 10733.248672592843, 10964.177686673691, 11172.385518796544, 11511.884881151416, 11637.59268425635, 8205.958970205349, 10309.536070876678, 10499.580016799242, 10669.093127084285, 10860.185486088732, 11467.770644587228, 11649.708757281136, 11793.646190614296, 11801.645950621494, 11919.82120268214, 8128.512289262427, 8156.225158609923, 8163.3469322452875, 8238.217369349903, 8477.0, 11256.268342557707, 11394.658160255205, 11452.541898323974, 11748.177627565989, 11871.287722736319], "value": 10034.7768367138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1207.2877002568487, 1208.2689972566598, 1416.9443764395528, 1417.8582141785823, 1418.1632836626395, 10212.438315892632, 10627.202959778027, 10641.201909856753, 10647.84028239577, 10649.84025239622, 10266.435346055963, 10557.41934193621, 10598.682039538808, 10599.0990765785, 10626.46867656616, 2395.0778950104204, 2450.1791899713617, 2463.1994601754413, 2464.2484042367073, 2469.2221950085664, 2054.3939537836372, 2353.5528249632603, 2442.1818690738623, 2479.132303693702, 2485.63958226058, 2103.4215590712524, 2136.134865379524, 2385.2128797496785, 2466.260121963413, 2469.4814089041324, 2412.819038572114, 2413.095089341497, 2423.369923819802, 2425.6604075429454, 2428.611422172453, 2386.72552656445, 2419.649150873131, 2421.1767998880464, 2435.768601982809, 2441.8779061047053, 10025.147862431702, 10223.539940702613, 10253.43606101664, 10258.589656413735, 10300.381977081324, 6030.155778191057, 6279.686015699197, 6282.183316168915, 6299.433051025384, 6304.27500837405, 3722.5346831646043, 3804.619538046173, 3839.404892241707, 3966.464527288837, 4061.776602286873, 1124.1400328748432, 1158.536585365852, 1195.432169719385, 1210.1831263896834, 1214.9453274602663, 12908.290044047573, 14526.0, 14835.629109272246, 14837.25813709321, 14849.886258530654, 5284.735763211824, 5633.69014704187, 5637.802676906289, 5666.943330566724, 5748.281464816898, 12474.251544907334, 12506.874931250753, 12507.624771256877, 12512.311822849648, 12524.0, 6288.434040936338, 6291.307956124822, 6297.023961286008, 6299.0866324382705, 6303.117563541108, 5518.641288316247, 5663.490285874209, 5667.546596272288, 5730.7421166047625, 5745.511631511368, 4321.7406955582765, 4466.2630665940405, 4469.821207151677, 4470.463544374631, 4471.575200355961, 1366.685662297677, 1411.7458857405766, 1415.5116484812722, 1434.1323499282992, 1440.985590144085, 561.5866801131093, 659.581165959619, 743.2604558464362, 757.814335487809, 803.7628899474552, 4383.802728877208, 6271.310155882849, 6462.063000864849, 6527.652760302906, 6615.437687796495, 558.9860253493695, 559.2841163310968, 586.302300262686, 592.7747455966705, 695.5339922252103, 3909.179072394772, 3950.6641935435237, 4077.55146933837, 4127.628513433805, 4134.193832202692, 10880.945595271973, 12580.622581322576, 13167.407466664023, 13286.269255191057, 13313.93343033298, 13185.736285274432, 13189.802152967713, 13202.207867527979, 13203.471861125447, 13227.93386033083, 9388.295877809054, 9794.363366381162, 9819.36174148678, 9821.459819709842, 9848.310618256653, 6484.351564843478, 6530.0, 6542.0, 6744.393004629607, 6839.863202735818, 12017.579384721494, 12226.633201003984, 12341.0, 12349.073819463396, 12393.504259829866, 12099.939500302442, 12449.564265250672, 12459.813102803284, 12465.189762665761, 12471.93764031174, 8620.482771033758, 8976.28189744832, 8979.910200897908, 8982.461052336881, 8983.0, 9618.278629102706, 9625.663101791404, 9697.272704547328, 9712.805743884941, 9712.980137085788, 8197.385196110436, 8279.0, 8338.291245244221, 8382.119877412784, 8425.283850872744, 7994.84010319802, 8096.068952070352, 8162.591870406337, 8223.21879421454, 8307.335413167053, 7482.476226664299, 7633.389328853789, 7646.847063058819, 7650.693972241048, 7661.348785353084, 4990.451050384528, 5726.398728133652, 5866.120081987736, 5891.440313170242, 6029.758809647565, 5750.30996280449, 5858.121281807763, 5950.851228719317, 5994.3705910878725, 6033.687925684414], "value": 5040.703731645818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.58680624925086, 392.06296950288817, 406.4472317647998, 406.68888300450163, 409.4533797380497, 4616.746078965655, 6242.750289988407, 6282.0, 6285.874282514353, 6333.8099857004245, 5360.544353729928, 5921.970390148043, 6038.516918646509, 6111.0, 6152.661603611799, 3825.9617403826023, 4000.3198656564314, 4523.0, 4678.7894544745395, 4679.625629949596, 3900.9520001998894, 4051.6556092732008, 4641.0949864776385, 4657.650676199281, 4678.508756580561, 3502.7932402586334, 3832.2910261601555, 4439.644828413735, 4583.610393116589, 4608.769561521927, 3908.5700572936953, 4280.737182531129, 4578.381918441001, 4592.977035114836, 4598.9080218395775, 3328.5839270091237, 3438.8995521433153, 3680.043188770912, 3942.38892971587, 3972.4239985201984, 5397.595180361449, 5926.8814623707285, 6030.517558595346, 6040.154378387029, 6080.391960803884, 4791.233402655594, 5162.967406518711, 5376.91934620981, 5379.596530260246, 5412.83761487156, 4963.875903102451, 5726.0, 5766.596338256324, 6591.7363305467725, 6633.30350313215, 4255.8084886180195, 4365.493904602907, 4412.955870441287, 4414.536473670282, 4467.30756732707, 5384.488473595002, 5911.704414779244, 6789.287124851919, 6850.314968503159, 7021.1574611047, 4227.513835908849, 4430.623397011225, 4468.218061839165, 4503.144402563503, 4504.594586487232, 3874.748141370802, 4310.202612516695, 4561.475430325489, 4561.657875659339, 4563.589276965089, 4802.351682522814, 4946.406431228275, 5236.24074509194, 5239.397469291004, 5370.3287089113865, 6585.736570537125, 6586.538942274091, 6588.8023359299295, 6600.580875111874, 6623.701933413008, 1471.1761413608413, 1534.9155796431303, 1535.8464153584775, 1554.4481708993371, 1555.4478160253173, 996.8405055191134, 1006.5269323417982, 1018.3024628129755, 1027.6403258859339, 1027.7841653252901, 6198.318184999646, 6210.285817130997, 6210.875782484325, 6235.719392627343, 6251.78118765841, 5784.132380143013, 6775.322467753185, 6832.968548556376, 6833.555818871758, 6840.863182736417, 4849.733264670406, 5442.755076021588, 5744.827655170351, 6130.662813545208, 6198.349173336825, 2968.85155742212, 3270.9836450817593, 3532.434810430319, 3545.2554963457965, 3551.5261166615564, 6060.272987070092, 6929.965350173317, 6971.860562788817, 6987.720491180397, 6987.930120698829, 5896.292444906553, 6722.831929201809, 6959.304069593, 6975.6163411012885, 6989.370956613828, 6603.9009414859775, 6891.896621550581, 6995.8950615740805, 7024.789256322319, 7049.647517624201, 6693.698783554654, 6697.837989050183, 6714.261431242657, 6715.93284067153, 6728.461723062045, 5836.708164591669, 6031.73809821317, 6174.753009879554, 6378.712957916998, 6688.297728738413, 5112.3865136183895, 5812.0, 6918.578497526027, 6957.234704182435, 7019.754308599175, 4069.7354671946227, 4397.846075387346, 4444.533324001062, 4460.620847228022, 4473.955260447354, 5642.492175704207, 6573.5069869759, 6601.669916504252, 6719.596824190564, 6824.658767061726, 5460.34475862899, 6391.392817682313, 6426.260979987174, 6448.838779030374, 6459.967700161657, 5510.504054635102, 5956.702164891824, 6432.195975503062, 6472.288048314587, 6475.222973243241, 5093.567046801063, 6271.874562508816, 6358.728254349056, 6366.9681651591445, 6404.455621272244, 5984.431479009487, 6449.161608990801, 6461.773837915838, 6477.190351206099, 6478.343879009583, 4754.904901902012, 6054.515638748978, 6465.224173099258, 6468.741250349934, 6470.223573171062], "value": 4701.873915300795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.1881055596624, 717.4690728860638, 723.7901008707473, 731.9304666056722, 762.1387831750125, 14558.272086395678, 15476.535703928887, 15570.688586228283, 17074.573135671613, 18106.728399074025, 14609.780853287182, 17013.319467221296, 17142.0, 17541.21064552095, 17610.207540660667, 941.5292353823088, 951.6003278622995, 954.179405711088, 955.2358113509199, 956.550421301987, 931.1014870649822, 965.3435663748648, 969.937917979811, 974.9366291191085, 976.7997560500116, 1007.566746299091, 1007.7984403119369, 1010.8584798128268, 1010.8736407949007, 1010.9797804043915, 1002.5839276700161, 1004.3120462483222, 1004.8542961270612, 1004.9949750251275, 1008.6721815409948, 745.4967896669726, 748.5658318175446, 749.7788152495027, 750.1373420566392, 750.5684231566817, 15733.44932927342, 16076.356945722157, 16263.268152933146, 16274.267657955303, 16544.255508502025, 14605.269736513132, 14660.774888285101, 14700.264986750726, 14727.0, 14868.0, 9002.324825638038, 10628.574857005711, 10741.71099468061, 11501.194916355858, 11559.94220028903, 2954.23189970607, 3047.3143542702937, 3078.615173103362, 3121.9687803122133, 3125.546795714609, 104628.47685761523, 105244.0, 105474.0, 106971.0, 108103.0, 9571.664991725256, 9757.853632195523, 11163.386013769321, 11201.495932683049, 11312.660620181408, 22589.0, 23752.0, 23762.287131386085, 24835.379115521897, 24968.875155624468, 7513.812154696177, 7783.532988020737, 7960.562169080757, 8016.198380162054, 8240.381971352173, 4086.9795651021554, 4140.648044916215, 4180.623743863067, 4207.074443622399, 4231.619154276068, 4163.2298024865495, 4169.10364271677, 4196.748195108242, 4235.830566777356, 4249.298865687127, 771.7607541662103, 780.478067767855, 782.8708263136633, 787.189195129018, 803.0604193094094, 534.5109225059106, 566.4448840136689, 567.1528924797493, 568.5196009372074, 585.783260193731, 10116.74708132288, 10191.337563058378, 10305.731410469307, 10447.030617851378, 10651.946740266249, 545.5008667069587, 574.827551734478, 586.6099044135602, 586.6714639801725, 597.1311741416229, 4618.630509559227, 4734.5339551904335, 4765.072022078159, 4793.448753393334, 4825.276208568743, 107101.0, 107668.0, 109469.0, 110284.0, 111467.0, 86784.56607717047, 93724.53137734268, 94504.05495944952, 95659.56510652346, 95891.0410895896, 82860.0, 84281.0, 84741.57629211938, 84905.57547212225, 86645.0, 7723.806904827199, 9320.207782338306, 9547.188488978514, 9555.761105972406, 9569.904300956901, 39298.80350598229, 39624.92558047277, 40500.79749601233, 41117.79441102775, 41212.793936031325, 39428.605713942496, 51673.708157296365, 52270.73864630653, 52567.73716131395, 53283.93432131413, 11492.253003554742, 11669.824952625548, 11682.4742886572, 12095.153339266168, 12303.138780285293, 12362.814557781461, 12626.625386996904, 12696.555620553236, 12717.0, 12901.419436125585, 11583.884161158281, 11743.823842642196, 11897.643070707893, 12203.413556237632, 12216.620943061704, 11704.590339338083, 11808.394726057017, 11948.283103013848, 11987.280763154238, 12242.15313650385, 10049.748756281151, 11652.766944660889, 11895.821562676394, 11929.46317415701, 12014.519419223134, 6685.498587605852, 7868.488548244346, 7949.7217597383815, 7970.362371010421, 8132.552709600792, 7969.203079691984, 8740.388172827841, 8786.868196976922, 8849.53982592865, 9148.817023659356], "value": 7321.622513371984, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1660.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 123.0, 123.0, 122.0, 124.0, 125.0, 124.0, 125.0, 123.0, 124.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 119.0, 166.0, 157.0, 123.0, 123.0, 119.0, 142.0, 120.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 210.0, 214.0, 210.0, 216.0, 212.0, 215.0, 216.0, 217.0, 211.0], "value": 213.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 63.0, 65.0, 102.0, 68.0, 64.0, 63.0, 64.0, 85.0, 64.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 75.0, 98.0, 77.0, 75.0, 75.0, 92.0, 76.0, 73.0, 93.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 133.0, 95.0, 94.0, 110.0, 137.0, 94.0, 93.0, 93.0, 95.0], "value": 108.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 102.0, 101.0, 99.0, 115.0, 150.0, 103.0, 117.0, 161.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 102.0, 103.0, 164.0, 157.0, 100.0, 104.0, 102.0, 102.0, 101.0], "value": 113.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 73.0, 96.0, 65.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 71.0, 69.0, 64.0, 64.0, 63.0, 63.0, 64.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 90.0, 82.0, 89.0, 120.0, 114.0, 116.0, 89.0, 91.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 171.0, 151.0, 164.0, 156.0, 166.0, 154.0, 149.0, 223.0, 203.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 203.0, 204.0, 201.0, 203.0, 208.0, 230.0, 258.0, 265.0, 201.0], "value": 217.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 75.0, 75.0, 75.0, 74.0, 77.0, 78.0, 76.0], "value": 75.9, "unit": "score"}]}]} 11:05:21 INFO - Return code: 0 11:05:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:05:21 ERROR - # TBPL SUCCESS # 11:05:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:05:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:05:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:05:21 INFO - 2017-03-28 11:05:21.138 screenresolution[1648:9726] starting screenresolution argv=screenresolution get 11:05:21 INFO - 2017-03-28 11:05:21.142 screenresolution[1648:9726] Display 0: 1600x1200x32@60 11:05:21 INFO - 2017-03-28 11:05:21.148 screenresolution[1649:9729] starting screenresolution argv=screenresolution list 11:05:21 INFO - Available Modes on Display 0 11:05:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:05:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:05:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:05:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:05:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:05:21 INFO - Intel Iris: 11:05:21 INFO - Chipset Model: Intel Iris 11:05:21 INFO - Type: GPU 11:05:21 INFO - Bus: Built-In 11:05:21 INFO - VRAM (Dynamic, Max): 1536 MB 11:05:21 INFO - Vendor: Intel (0x8086) 11:05:21 INFO - Device ID: 0x0a2e 11:05:21 INFO - Revision ID: 0x0009 11:05:21 INFO - Displays: 11:05:21 INFO - Display: 11:05:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:05:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:05:21 INFO - Display Serial Number: 1600x1200 60 11:05:21 INFO - Main Display: Yes 11:05:21 INFO - Mirror: Off 11:05:21 INFO - Online: Yes 11:05:21 INFO - Rotation: Supported 11:05:21 INFO - Return code: 0 11:05:21 INFO - Running post-action listener: _resource_record_post_action 11:05:21 INFO - [mozharness: 2017-03-28 18:05:21.390296Z] Finished run-tests step (success) 11:05:21 INFO - Running post-run listener: _resource_record_post_run 11:05:21 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 38294528; Write bytes: 511388672; Read time: 407; Write time: 1595 11:05:21 INFO - TinderboxPrint: CPU usage
53.5% 11:05:21 INFO - TinderboxPrint: I/O read bytes / time
38,294,528 / 407 11:05:21 INFO - TinderboxPrint: I/O write bytes / time
511,388,672 / 1,595 11:05:21 INFO - TinderboxPrint: CPU idle
2,247.4 (46.5%) 11:05:21 INFO - TinderboxPrint: CPU system
965.9 (20.0%) 11:05:21 INFO - TinderboxPrint: CPU user
1,616.4 (33.5%) 11:05:21 INFO - TinderboxPrint: Swap in / out
247,779,328 / 0 11:05:21 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203485696; Write bytes: 204982272; Read time: 15658; Write time: 399 11:05:21 INFO - run-tests - Wall time: 1187s; CPU: 53.0%; Read bytes: 37254144; Write bytes: 296436736; Read time: 386; Write time: 1163 11:05:21 INFO - Running post-run listener: _upload_blobber_files 11:05:21 INFO - Blob upload gear active. 11:05:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:05:21 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 11:05:21 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'] 11:05:21 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 11:05:22 INFO - (blobuploader) - INFO - Open directory for files ... 11:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:05:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:23 INFO - (blobuploader) - INFO - Done attempting. 11:05:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:05:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:24 INFO - (blobuploader) - INFO - Done attempting. 11:05:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:05:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:26 INFO - (blobuploader) - INFO - Done attempting. 11:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:05:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:28 INFO - (blobuploader) - INFO - Done attempting. 11:05:28 INFO - (blobuploader) - INFO - Iteration through files over. 11:05:28 INFO - Return code: 0 11:05:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:05:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:05:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55a00e4195eb31742d1e70fcda47cfbd482e36fac50f34a46efe4667a2ba0a81b6d7bbe32a05050793d24f0577064e595a23a40eb3ceab962d3541339391a560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447d78002f524c9dae77c78b2384fe2a76d34f1530d1c8111548dd54fd3564185d83835b82eb11e83e29fae387f5348b0183d75b8419a55e20045f6f1d8f4cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a6c292097d5169646f813a5c8d9ce3c700a82c6be0e65a15a244a663909708a1cac2f48fcf4eee24774bcb41419e8a19a3b96c1af7e0f3714f4af86c61ce79", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d75a3c8473fd8e9b4883d99f39ba091531e04d4a73d517c1233bb019c08737f43266706817a557430d18aa39387c129f30d88cc81f5aa1cd774ad51170e5fbf0"} 11:05:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:05:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:05:28 INFO - Contents: 11:05:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55a00e4195eb31742d1e70fcda47cfbd482e36fac50f34a46efe4667a2ba0a81b6d7bbe32a05050793d24f0577064e595a23a40eb3ceab962d3541339391a560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447d78002f524c9dae77c78b2384fe2a76d34f1530d1c8111548dd54fd3564185d83835b82eb11e83e29fae387f5348b0183d75b8419a55e20045f6f1d8f4cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a6c292097d5169646f813a5c8d9ce3c700a82c6be0e65a15a244a663909708a1cac2f48fcf4eee24774bcb41419e8a19a3b96c1af7e0f3714f4af86c61ce79", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d75a3c8473fd8e9b4883d99f39ba091531e04d4a73d517c1233bb019c08737f43266706817a557430d18aa39387c129f30d88cc81f5aa1cd774ad51170e5fbf0"} 11:05:28 INFO - Running post-run listener: copy_logs_to_upload_dir 11:05:28 INFO - Copying logs to upload dir... 11:05:28 INFO - mkdir: /builds/slave/test/build/upload/logs 11:05:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.435931 ========= master_lag: 0.72 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2017-03-28 11:05:29.285908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:05:29.289085) ========= 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.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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/55a00e4195eb31742d1e70fcda47cfbd482e36fac50f34a46efe4667a2ba0a81b6d7bbe32a05050793d24f0577064e595a23a40eb3ceab962d3541339391a560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447d78002f524c9dae77c78b2384fe2a76d34f1530d1c8111548dd54fd3564185d83835b82eb11e83e29fae387f5348b0183d75b8419a55e20045f6f1d8f4cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a6c292097d5169646f813a5c8d9ce3c700a82c6be0e65a15a244a663909708a1cac2f48fcf4eee24774bcb41419e8a19a3b96c1af7e0f3714f4af86c61ce79", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d75a3c8473fd8e9b4883d99f39ba091531e04d4a73d517c1233bb019c08737f43266706817a557430d18aa39387c129f30d88cc81f5aa1cd774ad51170e5fbf0"} build_url:https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011057 build_url: 'https://queue.taskcluster.net/v1/task/fxRlbONXRd6szvM54XCA2A/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/55a00e4195eb31742d1e70fcda47cfbd482e36fac50f34a46efe4667a2ba0a81b6d7bbe32a05050793d24f0577064e595a23a40eb3ceab962d3541339391a560", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/447d78002f524c9dae77c78b2384fe2a76d34f1530d1c8111548dd54fd3564185d83835b82eb11e83e29fae387f5348b0183d75b8419a55e20045f6f1d8f4cb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93a6c292097d5169646f813a5c8d9ce3c700a82c6be0e65a15a244a663909708a1cac2f48fcf4eee24774bcb41419e8a19a3b96c1af7e0f3714f4af86c61ce79", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d75a3c8473fd8e9b4883d99f39ba091531e04d4a73d517c1233bb019c08737f43266706817a557430d18aa39387c129f30d88cc81f5aa1cd774ad51170e5fbf0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:05:29.327923) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:05:29.328266) ========= 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.XGKmZwHvY7/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.F34jJytbyA/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.005517 ========= master_lag: 0.15 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:05:29.480001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:05:29.480355) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:05:29.480747) ========= ========= Total master_lag: 0.97 =========