builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0338 starttime: 1489854999.11 results: success (0) buildid: 20170318081720 builduid: 3f17cc8a1c6c4cbeb62b25e9a0d8ec7c revision: 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.108692) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.109162) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.109443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.134822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.135139) ========= 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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-18 09:36:39-- 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% 9.00M=0.001s 2017-03-18 09:36:39 (9.00 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.531950 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.690715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.691037) ========= 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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.019916 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:39.756054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 09:36:39.756401) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e --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 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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-18 09:36:39,862 truncating revision to first 12 chars 2017-03-18 09:36:39,862 Setting DEBUG logging. 2017-03-18 09:36:39,862 attempt 1/10 2017-03-18 09:36:39,862 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1a151f37b7d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-18 09:36:41,127 unpacking tar archive at: mozilla-central-1a151f37b7d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.530693 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-18 09:36:41.310819) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:41.311184) ========= script_repo_revision: 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:41.311592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:41.311877) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-18 09:36:41.331695) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-18 09:36:41.332093) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:36:41 INFO - MultiFileLogger online at 20170318 09:36:41 in /builds/slave/test 09:36:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:36:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:41 INFO - {'append_to_log': False, 09:36:41 INFO - 'base_work_dir': '/builds/slave/test', 09:36:41 INFO - 'blob_upload_branch': 'Firefox', 09:36:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:41 INFO - 'branch': 'Firefox', 09:36:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:41 INFO - 'config_files': ('talos/mac_config.py',), 09:36:41 INFO - 'default_actions': ('clobber', 09:36:41 INFO - 'read-buildbot-config', 09:36:41 INFO - 'download-and-extract', 09:36:41 INFO - 'populate-webroot', 09:36:41 INFO - 'create-virtualenv', 09:36:41 INFO - 'install', 09:36:41 INFO - 'run-tests'), 09:36:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:41 INFO - 'download_minidump_stackwalk': True, 09:36:41 INFO - 'download_symbols': 'ondemand', 09:36:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:41 INFO - 'gecko_profile': False, 09:36:41 INFO - 'gecko_profile_interval': 0, 09:36:41 INFO - 'installer_path': 'installer.exe', 09:36:41 INFO - 'log_level': 'info', 09:36:41 INFO - 'log_name': 'talos', 09:36:41 INFO - 'log_to_console': True, 09:36:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:41 INFO - 'opt_config_files': (), 09:36:41 INFO - 'pip_index': False, 09:36:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:41 INFO - 'cmd': ('bash', 09:36:41 INFO - '-c', 09:36:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:41 INFO - 'enabled': True, 09:36:41 INFO - 'halt_on_failure': False, 09:36:41 INFO - 'name': 'check_screen_resolution'},), 09:36:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:41 INFO - 'cmd': ('bash', 09:36:41 INFO - '-c', 09:36:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:41 INFO - 'enabled': True, 09:36:41 INFO - 'halt_on_failure': False, 09:36:41 INFO - 'name': 'check_screen_resolution'},), 09:36:41 INFO - 'run_cmd_checks_enabled': True, 09:36:41 INFO - 'suite': 'dromaeojs-e10s', 09:36:41 INFO - 'system_bits': '32', 09:36:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:41 INFO - 'title': 't-yosemite-r7-0338', 09:36:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:41 INFO - 'use_talos_json': True, 09:36:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:41 INFO - 'work_dir': 'build'} 09:36:41 INFO - [mozharness: 2017-03-18 16:36:41.560883Z] Running clobber step. 09:36:41 INFO - Running pre-action listener: _resource_record_pre_action 09:36:41 INFO - Running main action method: clobber 09:36:41 INFO - rmtree: /builds/slave/test/build 09:36:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:43 INFO - Running post-action listener: _resource_record_post_action 09:36:43 INFO - [mozharness: 2017-03-18 16:36:43.492054Z] Finished clobber step (success) 09:36:43 INFO - [mozharness: 2017-03-18 16:36:43.492227Z] Running read-buildbot-config step. 09:36:43 INFO - Running pre-action listener: _resource_record_pre_action 09:36:43 INFO - Running main action method: read_buildbot_config 09:36:43 INFO - Using buildbot properties: 09:36:43 INFO - { 09:36:43 INFO - "project": "", 09:36:43 INFO - "product": "firefox", 09:36:43 INFO - "script_repo_revision": "production", 09:36:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:36:43 INFO - "repository": "", 09:36:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:36:43 INFO - "buildid": "20170318081720", 09:36:43 INFO - "pgo_build": "False", 09:36:43 INFO - "basedir": "/builds/slave/test", 09:36:43 INFO - "buildnumber": 20, 09:36:43 INFO - "slavename": "t-yosemite-r7-0338", 09:36:43 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:36:43 INFO - "platform": "yosemite_r7", 09:36:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:36:43 INFO - "branch": "mozilla-central", 09:36:43 INFO - "repo_path": "mozilla-central", 09:36:43 INFO - "revision": "1a151f37b7d6b8ce3259e17ad7971a7d4899f55e", 09:36:43 INFO - "stage_platform": "macosx64", 09:36:43 INFO - "builduid": "3f17cc8a1c6c4cbeb62b25e9a0d8ec7c", 09:36:43 INFO - "slavebuilddir": "test" 09:36:43 INFO - } 09:36:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:36:43 INFO - Running post-action listener: _resource_record_post_action 09:36:43 INFO - [mozharness: 2017-03-18 16:36:43.495818Z] Finished read-buildbot-config step (success) 09:36:43 INFO - [mozharness: 2017-03-18 16:36:43.495958Z] Running download-and-extract step. 09:36:43 INFO - Running pre-action listener: _resource_record_pre_action 09:36:43 INFO - Running main action method: download_and_extract 09:36:43 INFO - mkdir: /builds/slave/test/build/tests 09:36:43 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')]} 09:36:43 INFO - trying https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:36:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/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 09:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/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 09:36:44 INFO - Downloaded 1325 bytes. 09:36:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:36:44 INFO - Using the following test package requirements: 09:36:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:36:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:36:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:36:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'jsshell-mac.zip'], 09:36:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:36:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:36:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:36:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:36:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:36:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:36:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:36:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:44 INFO - Fetch https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:36:48 INFO - Expected file size: 52475654 09:36:48 INFO - Obtained file size: 52475654 09:37:04 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 09:37:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:37:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:37:04 INFO - Fetch https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:37:06 INFO - Expected file size: 52475654 09:37:06 INFO - Obtained file size: 52475654 09:37:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:37:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:37:22 INFO - Fetch https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:37:23 INFO - Expected file size: 14404171 09:37:23 INFO - Obtained file size: 14404171 09:37:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:24 INFO - trying https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:24 INFO - Downloading https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/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 09:37:26 INFO - Downloaded 75117742 bytes. 09:37:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:26 INFO - mkdir: /builds/slave/test/properties 09:37:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:26 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:26 INFO - Contents: 09:37:26 INFO - build_url:https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:37:26 INFO - Running post-action listener: _resource_record_post_action 09:37:26 INFO - Running post-action listener: set_extra_try_arguments 09:37:26 INFO - [mozharness: 2017-03-18 16:37:26.872553Z] Finished download-and-extract step (success) 09:37:26 INFO - [mozharness: 2017-03-18 16:37:26.872693Z] Running populate-webroot step. 09:37:26 INFO - Running pre-action listener: _resource_record_pre_action 09:37:26 INFO - Running main action method: populate_webroot 09:37:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:26 INFO - '--tppagecycles', 09:37:26 INFO - '7'], 09:37:26 INFO - 'tests': ['tsvgm']}}, 09:37:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['damp', 'tps']}, 09:37:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'tests': ['damp', 'tps']}, 09:37:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['dromaeo_dom']}, 09:37:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['basic_compositor_video']}, 09:37:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['a11yr', 09:37:26 INFO - 'ts_paint', 09:37:26 INFO - 'tpaint', 09:37:26 INFO - 'sessionrestore', 09:37:26 INFO - 'sessionrestore_no_auto_restore', 09:37:26 INFO - 'tabpaint']}, 09:37:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['tsvgx', 09:37:26 INFO - 'tsvgr_opacity', 09:37:26 INFO - 'tart', 09:37:26 INFO - 'tscrollx', 09:37:26 INFO - 'cart', 09:37:26 INFO - 'tsvg_static']}, 09:37:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:26 INFO - 'tsvgr_opacity', 09:37:26 INFO - 'tart', 09:37:26 INFO - 'tscrollx', 09:37:26 INFO - 'cart', 09:37:26 INFO - 'tsvg_static']}, 09:37:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'talos_options': ['--disable-e10s'], 09:37:26 INFO - 'tests': ['tp5o']}, 09:37:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:37:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'talos_options': ['--disable-e10s', 09:37:26 INFO - '--xperf_path', 09:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:26 INFO - 'tests': ['tp5n']}, 09:37:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:37:26 INFO - 'talos_options': ['--xperf_path', 09:37:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:26 INFO - 'tests': ['tp5n']}}, 09:37:26 INFO - 'talos.zip': {'path': '', 09:37:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:26 INFO - Running post-action listener: _resource_record_post_action 09:37:26 INFO - [mozharness: 2017-03-18 16:37:26.891659Z] Finished populate-webroot step (success) 09:37:26 INFO - [mozharness: 2017-03-18 16:37:26.891800Z] Running create-virtualenv step. 09:37:26 INFO - Running pre-action listener: _resource_record_pre_action 09:37:26 INFO - Running main action method: create_virtualenv 09:37:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:26 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 09:37:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:27 INFO - Using real prefix '/tools/python27' 09:37:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:30 INFO - Installing pip.................done. 09:37:30 INFO - Return code: 0 09:37:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:31 INFO - Reading from file tmpfile_stdout 09:37:31 INFO - Output received: 09:37:31 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) 09:37:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:31 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:31 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 09:37:31 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 09:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:31 INFO - 'HOME': '/Users/cltbld', 09:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:31 INFO - 'LOGNAME': 'cltbld', 09:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:31 INFO - 'MOZ_NO_REMOTE': '1', 09:37:31 INFO - 'NO_EM_RESTART': '1', 09:37:31 INFO - 'PAGER': '/bin/cat', 09:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:31 INFO - 'PWD': '/builds/slave/test', 09:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:31 INFO - 'SHELL': '/bin/bash', 09:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:31 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:31 INFO - 'USER': 'cltbld', 09:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:31 INFO - 'XPC_FLAGS': '0x0', 09:37:31 INFO - 'XPC_SERVICE_NAME': '0', 09:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:31 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 09:37:31 INFO - Cleaning up... 09:37:31 INFO - Return code: 0 09:37:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:31 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:31 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 09:37:31 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 09:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:31 INFO - 'HOME': '/Users/cltbld', 09:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:31 INFO - 'LOGNAME': 'cltbld', 09:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:31 INFO - 'MOZ_NO_REMOTE': '1', 09:37:31 INFO - 'NO_EM_RESTART': '1', 09:37:31 INFO - 'PAGER': '/bin/cat', 09:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:31 INFO - 'PWD': '/builds/slave/test', 09:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:31 INFO - 'SHELL': '/bin/bash', 09:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:31 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:31 INFO - 'USER': 'cltbld', 09:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:31 INFO - 'XPC_FLAGS': '0x0', 09:37:31 INFO - 'XPC_SERVICE_NAME': '0', 09:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:31 INFO - Downloading/unpacking psutil>=3.1.1 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:31 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 09:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:35 INFO - Installing collected packages: psutil 09:37:35 INFO - Running setup.py install for psutil 09:37:35 INFO - building 'psutil._psutil_osx' extension 09:37:35 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 09:37:35 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 09:37:36 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 09:37:36 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 09:37:36 INFO - building 'psutil._psutil_posix' extension 09:37:36 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 09:37:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:36 INFO - ^ 09:37:36 INFO - 1 warning generated. 09:37:36 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 09:37:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:36 INFO - Successfully installed psutil 09:37:36 INFO - Cleaning up... 09:37:36 INFO - Return code: 0 09:37:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:37:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:36 INFO - 'HOME': '/Users/cltbld', 09:37:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:36 INFO - 'LOGNAME': 'cltbld', 09:37:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:36 INFO - 'MOZ_NO_REMOTE': '1', 09:37:36 INFO - 'NO_EM_RESTART': '1', 09:37:36 INFO - 'PAGER': '/bin/cat', 09:37:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:36 INFO - 'PWD': '/builds/slave/test', 09:37:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:36 INFO - 'SHELL': '/bin/bash', 09:37:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:36 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:36 INFO - 'USER': 'cltbld', 09:37:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:36 INFO - 'XPC_FLAGS': '0x0', 09:37:36 INFO - 'XPC_SERVICE_NAME': '0', 09:37:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:39 INFO - Installing collected packages: mozsystemmonitor 09:37:39 INFO - Running setup.py install for mozsystemmonitor 09:37:39 INFO - Successfully installed mozsystemmonitor 09:37:39 INFO - Cleaning up... 09:37:39 INFO - Return code: 0 09:37:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:37:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:37:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:39 INFO - 'HOME': '/Users/cltbld', 09:37:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:39 INFO - 'LOGNAME': 'cltbld', 09:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:39 INFO - 'MOZ_NO_REMOTE': '1', 09:37:39 INFO - 'NO_EM_RESTART': '1', 09:37:39 INFO - 'PAGER': '/bin/cat', 09:37:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:39 INFO - 'PWD': '/builds/slave/test', 09:37:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:39 INFO - 'SHELL': '/bin/bash', 09:37:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:39 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:39 INFO - 'USER': 'cltbld', 09:37:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:39 INFO - 'XPC_FLAGS': '0x0', 09:37:39 INFO - 'XPC_SERVICE_NAME': '0', 09:37:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:39 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:42 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 09:37:42 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 09:37:42 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 09:37:42 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 09:37:42 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 09:37:42 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 09:37:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:42 INFO - warning: no files found matching '*.txt' 09:37:42 INFO - no previously-included directories found matching 'build' 09:37:42 INFO - no previously-included directories found matching 'dist' 09:37:42 INFO - no previously-included directories found matching '.git*' 09:37:42 INFO - Installing collected packages: jsonschema, functools32 09:37:43 INFO - Running setup.py install for functools32 09:37:43 INFO - warning: no files found matching '*.txt' 09:37:43 INFO - no previously-included directories found matching 'build' 09:37:43 INFO - no previously-included directories found matching 'dist' 09:37:43 INFO - no previously-included directories found matching '.git*' 09:37:43 INFO - Successfully installed jsonschema functools32 09:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:43 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')]} 09:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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')]} 09:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:43 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 09:37:43 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 09:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:43 INFO - 'HOME': '/Users/cltbld', 09:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:43 INFO - 'LOGNAME': 'cltbld', 09:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:43 INFO - 'MOZ_NO_REMOTE': '1', 09:37:43 INFO - 'NO_EM_RESTART': '1', 09:37:43 INFO - 'PAGER': '/bin/cat', 09:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:43 INFO - 'PWD': '/builds/slave/test', 09:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:43 INFO - 'SHELL': '/bin/bash', 09:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:43 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:43 INFO - 'USER': 'cltbld', 09:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:43 INFO - 'XPC_FLAGS': '0x0', 09:37:43 INFO - 'XPC_SERVICE_NAME': '0', 09:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:43 INFO - Cleaning up... 09:37:43 INFO - Return code: 0 09:37:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:43 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')]} 09:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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')]} 09:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:43 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:43 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 09:37:43 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 09:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:43 INFO - 'HOME': '/Users/cltbld', 09:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:43 INFO - 'LOGNAME': 'cltbld', 09:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:43 INFO - 'MOZ_NO_REMOTE': '1', 09:37:43 INFO - 'NO_EM_RESTART': '1', 09:37:43 INFO - 'PAGER': '/bin/cat', 09:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:43 INFO - 'PWD': '/builds/slave/test', 09:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:43 INFO - 'SHELL': '/bin/bash', 09:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:43 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:43 INFO - 'USER': 'cltbld', 09:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:43 INFO - 'XPC_FLAGS': '0x0', 09:37:43 INFO - 'XPC_SERVICE_NAME': '0', 09:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:46 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:46 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 09:37:46 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 09:37:46 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 09:37:46 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 09:37:46 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 09:37:46 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 09:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 INFO - Downloading docopt-0.6.1.tar.gz 09:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:47 INFO - Running setup.py install for blobuploader 09:37:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:47 INFO - Running setup.py install for requests 09:37:48 INFO - Running setup.py install for docopt 09:37:48 INFO - Successfully installed blobuploader requests docopt 09:37:48 INFO - Cleaning up... 09:37:48 INFO - Return code: 0 09:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:48 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')]} 09:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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')]} 09:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:48 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 09:37:48 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 09:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:48 INFO - 'HOME': '/Users/cltbld', 09:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:48 INFO - 'LOGNAME': 'cltbld', 09:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:48 INFO - 'MOZ_NO_REMOTE': '1', 09:37:48 INFO - 'NO_EM_RESTART': '1', 09:37:48 INFO - 'PAGER': '/bin/cat', 09:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:48 INFO - 'PWD': '/builds/slave/test', 09:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:48 INFO - 'SHELL': '/bin/bash', 09:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:48 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:48 INFO - 'USER': 'cltbld', 09:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:48 INFO - 'XPC_FLAGS': '0x0', 09:37:48 INFO - 'XPC_SERVICE_NAME': '0', 09:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:48 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Dum3rT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:48 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-_IUAYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-cIqxYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-XcGlYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-dJgdhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-nKwN_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-xF94tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-klC0GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-rCOduJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-0NQsrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:49 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-mvAR51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-vUIemw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-OQSqX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-C1he91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-sSJEV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-6XLuTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:50 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-GUQNr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:50 INFO - Running setup.py install for manifestparser 09:37:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:50 INFO - Running setup.py install for mozcrash 09:37:50 INFO - Running setup.py install for mozdebug 09:37:51 INFO - Running setup.py install for mozdevice 09:37:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Running setup.py install for mozfile 09:37:51 INFO - Running setup.py install for mozhttpd 09:37:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Running setup.py install for mozinfo 09:37:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Running setup.py install for mozInstall 09:37:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:51 INFO - Running setup.py install for mozleak 09:37:51 INFO - Running setup.py install for mozlog 09:37:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for moznetwork 09:37:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozprocess 09:37:52 INFO - Running setup.py install for mozprofile 09:37:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozrunner 09:37:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:52 INFO - Running setup.py install for mozscreenshot 09:37:52 INFO - Running setup.py install for moztest 09:37:53 INFO - Running setup.py install for mozversion 09:37:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:53 INFO - Cleaning up... 09:37:53 INFO - Return code: 0 09:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:53 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 09:37:53 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 09:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:53 INFO - 'HOME': '/Users/cltbld', 09:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:53 INFO - 'LOGNAME': 'cltbld', 09:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:53 INFO - 'MOZ_NO_REMOTE': '1', 09:37:53 INFO - 'NO_EM_RESTART': '1', 09:37:53 INFO - 'PAGER': '/bin/cat', 09:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:53 INFO - 'PWD': '/builds/slave/test', 09:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:53 INFO - 'SHELL': '/bin/bash', 09:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:53 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:53 INFO - 'USER': 'cltbld', 09:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:53 INFO - 'XPC_FLAGS': '0x0', 09:37:53 INFO - 'XPC_SERVICE_NAME': '0', 09:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Ap9lPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:53 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)) 09:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-LamVBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:53 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)) 09:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tX_fVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:53 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)) 09:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-HpoCwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:53 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)) 09:37:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-iAi2rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-7goNqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-vt_CLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-BCRxv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fixcwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-yQmxIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9_ondO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Mbd7Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-WU4sAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-EjQybn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:54 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)) 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:54 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-elgaG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-lJ6Lbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:55 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)) 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:55 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ex2Iij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:55 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)) 09:37:55 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)) 09:37:55 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)) 09:37:55 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)) 09:37:55 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)) 09:37:55 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)) 09:37:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:57 INFO - Downloading blessings-1.6.tar.gz 09:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:58 INFO - Installing collected packages: blessings 09:37:58 INFO - Running setup.py install for blessings 09:37:58 INFO - Successfully installed blessings 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:58 INFO - Reading from file tmpfile_stdout 09:37:58 INFO - Current package versions: 09:37:58 INFO - blessings == 1.6 09:37:58 INFO - blobuploader == 1.2.4 09:37:58 INFO - docopt == 0.6.1 09:37:58 INFO - functools32 == 3.2.3-2 09:37:58 INFO - jsonschema == 2.5.1 09:37:58 INFO - manifestparser == 1.1 09:37:58 INFO - mozInstall == 1.12 09:37:58 INFO - mozcrash == 1.0 09:37:58 INFO - mozdebug == 0.1 09:37:58 INFO - mozdevice == 0.49 09:37:58 INFO - mozfile == 1.2 09:37:58 INFO - mozhttpd == 0.7 09:37:58 INFO - mozinfo == 0.9 09:37:58 INFO - mozleak == 0.1 09:37:58 INFO - mozlog == 3.4 09:37:58 INFO - moznetwork == 0.27 09:37:58 INFO - mozprocess == 0.25 09:37:58 INFO - mozprofile == 0.28 09:37:58 INFO - mozrunner == 6.13 09:37:58 INFO - mozscreenshot == 0.1 09:37:58 INFO - mozsystemmonitor == 0.3 09:37:58 INFO - moztest == 0.8 09:37:58 INFO - mozversion == 1.4 09:37:58 INFO - psutil == 3.1.1 09:37:58 INFO - requests == 1.2.3 09:37:58 INFO - wsgiref == 0.1.2 09:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:58 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 09:37:58 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 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 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)) 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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 0x1063f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb81576fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, '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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 09:37:58 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 09:37:58 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 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:59 INFO - Cleaning up... 09:37:59 INFO - Return code: 0 09:37:59 INFO - Running post-action listener: _resource_record_post_action 09:37:59 INFO - Running post-action listener: _start_resource_monitoring 09:37:59 INFO - Starting resource monitoring. 09:37:59 INFO - [mozharness: 2017-03-18 16:37:59.113958Z] Finished create-virtualenv step (success) 09:37:59 INFO - [mozharness: 2017-03-18 16:37:59.114974Z] Running install step. 09:37:59 INFO - Running pre-action listener: _resource_record_pre_action 09:37:59 INFO - Running main action method: install 09:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:59 INFO - Reading from file tmpfile_stdout 09:37:59 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:59 INFO - Reading from file tmpfile_stdout 09:37:59 INFO - Output received: 09:37:59 INFO - Usage: mozinstall [options] installer 09:37:59 INFO - Options: 09:37:59 INFO - -h, --help show this help message and exit 09:37:59 INFO - -d DEST, --destination=DEST 09:37:59 INFO - Directory to install application into. [default: 09:37:59 INFO - "/builds/slave/test"] 09:37:59 INFO - --app=APP Application being installed. [default: firefox] 09:37:59 INFO - mkdir: /builds/slave/test/build/application 09:37:59 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'] 09:37:59 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 09:38:19 INFO - Reading from file tmpfile_stdout 09:38:19 INFO - Output received: 09:38:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:19 INFO - Running post-action listener: _resource_record_post_action 09:38:19 INFO - [mozharness: 2017-03-18 16:38:19.403559Z] Finished install step (success) 09:38:19 INFO - [mozharness: 2017-03-18 16:38:19.403726Z] Running run-tests step. 09:38:19 INFO - Running pre-action listener: _resource_record_pre_action 09:38:19 INFO - Running main action method: run_tests 09:38:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:19 INFO - 2017-03-18 09:38:19.416 screenresolution[1926:17535] starting screenresolution argv=screenresolution get 09:38:19 INFO - 2017-03-18 09:38:19.419 screenresolution[1926:17535] Display 0: 1600x1200x32@60 09:38:19 INFO - 2017-03-18 09:38:19.424 screenresolution[1927:17537] starting screenresolution argv=screenresolution list 09:38:19 INFO - Available Modes on Display 0 09:38:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:19 INFO - Intel Iris: 09:38:19 INFO - Chipset Model: Intel Iris 09:38:19 INFO - Type: GPU 09:38:19 INFO - Bus: Built-In 09:38:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:19 INFO - Vendor: Intel (0x8086) 09:38:19 INFO - Device ID: 0x0a2e 09:38:19 INFO - Revision ID: 0x0009 09:38:19 INFO - Displays: 09:38:19 INFO - Display: 09:38:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:19 INFO - Display Serial Number: 1600x1200 60 09:38:19 INFO - Main Display: Yes 09:38:19 INFO - Mirror: Off 09:38:19 INFO - Online: Yes 09:38:19 INFO - Rotation: Supported 09:38:19 INFO - Return code: 0 09:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:19 INFO - Python 2.7.3 09:38:19 INFO - Return code: 0 09:38:19 INFO - grabbing minidump binary from tooltool 09:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:19 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 0x1061cfd30>, '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 0x1060f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061c4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:19 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 09:38:19 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 09:38:19 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 09:38:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:19 INFO - Return code: 0 09:38:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:19 INFO - ENV: RUST_BACKTRACE is now 1 09:38:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WEXBvQtoBC/Render', 09:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:19 INFO - 'HOME': '/Users/cltbld', 09:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:19 INFO - 'LOGNAME': 'cltbld', 09:38:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:19 INFO - 'MOZ_NO_REMOTE': '1', 09:38:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:19 INFO - 'NO_EM_RESTART': '1', 09:38:19 INFO - 'PAGER': '/bin/cat', 09:38:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:19 INFO - 'PWD': '/builds/slave/test', 09:38:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:19 INFO - 'RUST_BACKTRACE': '1', 09:38:19 INFO - 'SHELL': '/bin/bash', 09:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ys7IIQ0tyx/Listeners', 09:38:19 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 09:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:19 INFO - 'USER': 'cltbld', 09:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:19 INFO - 'XPC_FLAGS': '0x0', 09:38:19 INFO - 'XPC_SERVICE_NAME': '0', 09:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:38:20 INFO - mozversion application_buildid: 20170318081720 09:38:20 INFO - mozversion application_changeset: 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e 09:38:20 INFO - mozversion application_display_name: Nightly 09:38:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:20 INFO - mozversion application_name: Firefox 09:38:20 INFO - mozversion application_remotingname: firefox 09:38:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:38:20 INFO - mozversion application_vendor: Mozilla 09:38:20 INFO - mozversion application_version: 55.0a1 09:38:20 INFO - mozversion platform_buildid: 20170318081720 09:38:20 INFO - mozversion platform_changeset: 1a151f37b7d6b8ce3259e17ad7971a7d4899f55e 09:38:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:38:20 INFO - mozversion platform_version: 55.0a1 09:38:20 INFO - using testdate: 1489855100 09:38:20 INFO - actual date: 1489855100 09:38:20 INFO - starting webserver on 'localhost:53415' 09:38:20 INFO - SUITE-START | Running 2 tests 09:38:20 INFO - TEST-START | dromaeo_css 09:38:20 INFO - operating with platform_type : mac_ 09:38:20 INFO - Initialising browser for dromaeo_css test... 09:38:20 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpFkib06/profile http://localhost:53415/getInfo.html) 09:38:22 INFO - PID 1937 | 2017-03-18 09:38:22.867 plugin-container[1939:17719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:38:22 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:22 INFO - PID 1937 | 2017-03-18 09:38:22.872 plugin-container[1939:17719] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:38:22 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:22 INFO - PID 1937 | 2017-03-18 09:38:22.872 plugin-container[1939:17719] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:22 INFO - PID 1937 | __metrics Screen width/height:1600/1200 09:38:22 INFO - PID 1937 | colorDepth:24 09:38:22 INFO - PID 1937 | Browser inner width/height: 1024/689 09:38:22 INFO - PID 1937 | __metrics 09:38:23 ERROR - PID 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:38:23 INFO - TEST-INFO | 1937: exit 0 09:38:23 INFO - Browser initialized. 09:38:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:23 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpFkib06/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:26 INFO - PID 1940 | 2017-03-18 09:38:26.112 plugin-container[1941:17848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:38:26 INFO - PID 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:26 INFO - PID 1940 | 2017-03-18 09:38:26.112 plugin-container[1941:17848] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:38:26 INFO - PID 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:26 INFO - PID 1940 | 2017-03-18 09:38:26.112 plugin-container[1941:17848] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:35 INFO - PID 1940 | RSS: Main: 228679680 09:38:35 INFO - PID 1940 | 09:41:39 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53415/tests/dromaeo/cssquery-ext.html) 09:41:41 INFO - PID 1940 | RSS: Main: 250572800 09:41:41 INFO - PID 1940 | 09:43:24 INFO - PID 1940 | ************************* 09:43:24 INFO - PID 1940 | A coding exception was thrown and uncaught in a Task. 09:43:24 INFO - PID 1940 | 09:43:24 ERROR - PID 1940 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:43:24 INFO - PID 1940 | Full stack: 09:43:24 INFO - PID 1940 | ************************* 09:44:43 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-ext.html (next: http://localhost:53415/tests/dromaeo/cssquery-jquery.html) 09:44:44 INFO - PID 1940 | RSS: Main: 259821568 09:44:44 INFO - PID 1940 | 09:47:46 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53415/tests/dromaeo/cssquery-mootools.html) 09:47:47 INFO - PID 1940 | RSS: Main: 271679488 09:47:47 INFO - PID 1940 | 09:50:48 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53415/tests/dromaeo/cssquery-prototype.html) 09:50:49 INFO - PID 1940 | RSS: Main: 263966720 09:50:49 INFO - PID 1940 | 09:53:52 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53415/tests/dromaeo/cssquery-yui.html) 09:53:53 INFO - PID 1940 | RSS: Main: 274317312 09:53:53 INFO - PID 1940 | 09:56:55 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:53415/tests/dromaeo/cssquery-yui.html (next: http://localhost:53415/tests/dromaeo/cssquery-dojo.html) 09:56:56 INFO - PID 1940 | RSS: Main: 268124160 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | __start_tp_report 09:56:56 INFO - PID 1940 | _x_x_mozilla_page_load 09:56:56 INFO - PID 1940 | _x_x_mozilla_page_load_details 09:56:56 INFO - PID 1940 | |i|pagename|runs| 09:56:56 INFO - PID 1940 | |0;dojo.html;3448.5172075909377;3451.171718787491;3475.3744326021306;3528.435450327948;3531.0995696097493;5898.085796701507;6031.758729650809;6075.210222671052;6079.05774604937;6082.239720034996;5936.168936348904;6047.15339852421;6054;6101.489633126343;6138.664296166084;5390.51485366318;5438.755256013469;5443.755031023614;5475.890482190353;5535.363433205172;5379.166229234455;5442.265294185294;5465.863353416156;5493.340799104113;5527.723613819313;5331.493508116722;5462.808801691962;5464.043792336365;5493.066178749602;5548.889022219553;5352.982933242672;5377.381601115843;5423.674579525202;5429.891402171974;5437.891242175174;5310.097283461782;5375;5380.085385484477;5446.754896029688;5454.699991500466;5017.745563609098;5522.779088836442;5946.762129514815;6000.219971403733;6030.09548567714;6227.937720622826;6236.376362363772;6373.5857169283845;6412.454941329993;6456.612603243773;6484.740610375579;6516.641584712839;6526.934730652728;6535.411812936811;6545.083688283644;6198.0702894566175;6202.0696895465135;6202.534809889276;6203.038529028008;6208;6374.58565193261;6432;6437.985806015561;6622.9006564901565;6633.436157926534;6244.625322480575;6266.216722909637;6312.495000399957;6330.936690633127;6356.45970092538;6452.838679033061;6502.577332473374;6509.9023514647315;6511.511636627177;6515.153030106056;6277.780277690259;6382.553221274559;6403.423691867754;6434.64609446485;6502.902456463157;6264.498840092781;6288.056791481223;6414.037894315797;6431.871362572816;6438.291787903327;5207.869803254949;5220.138677118309;5255.343082114736;5261.579073674096;5270.631055826132;3878.825452854628;3929.8231579578987;4061.3704875743715;4630.814767409333;4640.489546149921;5045.84862454127;5186.714730689773;6131.877362452726;6153.907671391748;6158.353372895871;6300.0234963580715;6331.715072821642;6467.741290348427;6477.676116194171;6515.641639709862;6129.892971708966;6156.984097623931;6166.105914642351;6169.907451388232;6179.752809887555;5698.686572238484;5701.885962280814;5705.087186050295;5715.571332150105;5744.482996530249;6399.456046236122;6477.838054048687;6512.511561632802;6682.565633233824;6688.197416309879;6468.3855033771715;6518.543701940818;6518.771842985473;6518.967405162944;6542.116814230209;6671.8999215010845;6677.732890684319;6680.933190668031;6681.832954176184;6690.163729533809;5576.577972616957;6264.404881536247;6312.621242725268;6341.302456729848;6414.326495717883;5817.447342502455;6444.742210311535;6461.741530338733;6488.253850807028;6491.545591808528;6279.560430769802;6312.084747711464;6321.241451025722;6334.018227174611;6335.968320158369;6170.136180934763;6215.751369945332;6243.843903902439;6262.906056409249;6264.59280146789;6142;6275.52933529978;6303.558750887394;6319.431251187415;6319.684015799284;6234.501239900706;6264.59280146789;6278.591891527036;6292.748290068346;6310.337414571404;6223.564350495603;6272.121902933684;6274.466670333073;6303.747850085945;6307.96846015767 09:56:56 INFO - PID 1940 | |1;ext.html;664.6676661669165;669.3373560175427;683.5591043776767;729.3217307903651;797.3621103117505;36649;37448.43827342588;37461.81269093651;37483.62516374835;37511.81244093783;39446.80276598613;39875;40046.39930401046;40054;40085.19829603406;9089;9239.95380023099;9256.583453744564;9266.629334826599;10651.25441219115;10825.621103261388;10984.835227471554;10998.010179083882;11004.724881877937;11015.944920275388;9322.76693082675;10938.890611093866;10967.725806854813;11010.669679909615;11037.392943388111;9518;10919.672409827717;10980.066694330992;10984.560617575327;10991.835122473169;10632.096271816905;10649.414282214475;10747.408892510908;10756.462176891202;10782.5686972521;37459.62540374616;37741.433878491844;37809.621903780884;37842.81078594617;37893.242135157416;3807.676347510465;3812.3519001769764;3847.9230415391817;3849.5957924418094;3994.320965435883;21430.785692143192;27645.585316220073;27946.720532794818;28422.857885710648;28451.715482845113;3082.321889184376;3164.2247649326064;3239.5302681111343;3273.672632736731;3283.6223834259126;338077.3096134528;342429;342746;344309;350754;52349.47650523522;52427;52502.737486312326;52541;52588.21117683238;45155.54844451514;50261.99476010459;54590.45409545933;55021.724891376085;55075.72462137744;14890.851091489163;14918.627034324012;14991.700165996837;15032.624184395478;15045.322960466805;12215.083868709882;12265.509379624715;12266.938665306616;12276.386180691108;12288.262704237774;11431.657050288504;12261.816072758917;13713.108647937852;14153.929230353988;14182.439931607514;6014.1580178775;6033.909491357633;6066.878662426815;6074.544409169242;6097.725602347905;974.9268804839666;1117.1230583991528;1119.2445099557765;1123.455124264731;1129.740159763259;23630.527389452116;24475;25756.484870302487;25810.96756129771;26069.73930260673;1206.1858245684127;1212.4362171590076;1232.0512310568402;1258.5406326690875;1267.8985681145487;11231.820658830868;13444.193348399127;13457.798133028013;13618.86381136176;13773.793393099111;352912;402800;408669;409529.8570521445;409569;40131;42221.78889105596;43172;55918.16122758162;58819.41180588225;1274.7698470975554;1365.5630198336437;1404.1855723680242;1407.4862675123711;1418.1916307704716;24418.87790561036;26377.944882204498;27260.454790904467;28687.713122868503;28695.13914582566;45775;45924.311135333664;46092.30861537012;46160.76919615381;46205.76897115493;459895.70052149525;463923;467009;467028;467298;33449.49825752567;33844.3231135371;34848.65151348454;35214.29571408609;35243;44430.3335449975;44610.77694611506;44674.77662611666;45935.85160371017;46131.077378452916;36145.457818133276;36859;37895;37904.81047594855;38093.61906381012;36054;36638.9755966219;36660.81669591635;36868;37479.43780843235;35209;36259.92361069399;36283.45574816327;36856.26287474182;36869.34087965994;31247.687523125387;32645.673543264264;32652;32825.67174328226;32998.67001329956;21334.559687280263;23742.881285594154;24350.756492435557;24684.753152468245;25414.271220831808 09:56:56 INFO - PID 1940 | |2;jquery.html;405.564018679919;406.8942075060487;423.5870026723945;433.36079283057495;437.66293971915786;20399.592008159845;20909;21259.57480850382;21789.237376691817;23372.41568960777;22410.88794556029;22961.205066073802;23321.650175247385;23324.5335093298;23486.060557577675;6569.244536878273;6685;6879.5184337096425;6943.1668199816095;6968.616726080064;6619.30497297784;6874.0720002799735;6877.931220687804;6883.139607549057;6887.552309099918;6488.837779055514;6679.632620205887;6776.593404395752;6874.931250687504;6888.862222755542;6709.597424154542;6769.255381907986;6776.119104516381;6795.150606174228;6800.65996700163;4979.502049794992;6601.63690996995;6633.369829866155;6638.800835974928;6738.157730283715;20056.495762817845;25375.492490150093;25588.616170757454;26668;27976.860115699496;2775.9722402775915;2866.656001279839;2927.985360073186;2963.9110826675233;3003.4743919813945;14453.638659033502;15450.459233926758;16064.036157830567;16324.918375408166;16697.83302166975;1718.5531761741913;1947.6981067934494;2005.7593088829433;2011.7585889693185;2021;119900;120923;156563.21718391334;157870;158066;21428;21725.67411488829;21954.34136975893;22118.88940555287;22286.665700014513;28108;35288.470672939926;35842.64157358393;35921.820390897876;35962.460563091576;10062.597496100074;10308.175345972304;10547.736306592396;10751.38729190612;10882.455877206108;8605.397622166389;8617.310615150773;8625.5437680321;8757.386982911263;8759.34304927133;10765.623203187852;10789.730256743644;10792.65091863517;10962.890371096346;10988.725281868017;4358.607725304738;4389.714668546598;4404.383386325917;4426.18115648606;4431.645468362523;768.5273556762662;917.1241464401614;922.8292765838207;924.7826760711228;960.5293406230896;17107;22644.547109057727;22764.317070487912;23199.840007999534;23220.88389558075;913.8400779863497;927.5640448988964;942.9104235097655;963.9903600963901;970.4274478057838;11932.76134477323;12139.091262445614;12265.202761820632;12304.138710290374;12343.444544995496;502354;660224;664696;665100.6744966244;684614;116429;116511;141990;142124;143673;1206.2762342594374;1208.7824191645589;1250.3623152192265;1250.9867007723774;1254.0531898416637;19134.61730765349;19689.606207876048;19951.301704440273;19969.700454492904;20144.697829532863;56036.719816400655;56720.14919776124;56895.71552142213;57267.85464290774;57709.84580308454;79266;82389;84033.68864847724;84837;92568.93207765894;10468.709760494294;10930.726731831768;11015.504302306255;11856.110791690486;12173.817392739293;11788.646340609796;13770.93114534421;13782.104163229758;13953.93023034919;14145.222012789396;11397.886021139682;11664.125190610568;11819;11866.40667966581;11871.940640297089;10487.685369438928;11584.131190160602;11618.825717614074;11792.23350482216;12069.456874440846;9066.592003359996;11385.9183377579;12051.216670916363;12099.33453660057;12202.206856554296;11779.998700110244;11934.820977685167;12075.215111017756;12215.144939854124;12256.75486490283;11499.94250028778;11507.906748858844;11542.538298467967;11807.350595717318;11813.11401644863 09:56:56 INFO - PID 1940 | |3;mootools.html;1407.5777266819957;1410.668492904168;1560.9531714048583;1598.704239715653;1663.5342104210822;9388.295877809158;9642.325037247396;10375.948120259398;10736.08743256823;10798.24412291139;9000.729978100651;10328.328658637183;10652.573897044109;10673.252872298943;10727.08819750322;2320.814334853216;2376.4534157143867;2379.9881000594974;2380.690510233676;2388.0925248405583;2315.47901722112;2346.683197768305;2360.775726305998;2363.8108951283943;2366.4438856868624;2322.1756276521846;2325.3953971967326;2336.030547322859;2342.496363281894;2350.4123969007746;2306.630939049761;2307.296274636227;2311.2257393773107;2316.4903721181313;2324.732655744586;1916.434651777729;2298.0463107810237;2298.678185054094;2350.471143992605;2366.8934897929635;9267.212286955617;10916.945415272952;10999.065079468255;10999.505022273937;11012.614558490495;4587.839425620121;5515.25544051556;5697.6866272355;5763.942360576424;5768.769249230026;2870.468963241807;2923.35686149049;3443.741719371032;3652.3425783358985;3668.3580373434543;1152.400751609167;1200.5857978997233;1215.3254943506352;1228.103484456343;1243.4155946705034;13976.720465590632;14063.859361406357;15194.696106077927;15234.93355465129;15266.465673701477;4112.568180341081;4478.48497422794;4576.382188404589;4594.448666160015;4806.759662016885;11043.619547556555;12444.253344799341;12644.873551264554;12780.488780448864;12780.680482987813;5830.416958304136;5916.940830591639;5923.17075609415;5948.2862056553495;5953.613015154001;4499.752513611717;4898.191798353232;5175.508326708957;5229.372475302988;5238.764255608507;3935.58676338986;3994.840206391712;4069.593040695907;4116.958830411658;4129.236091323055;1146.5528443906717;1147.770445910821;1148.1446322489799;1157.6121999130385;1162.1690491298762;855.6791203298762;939.8778158839307;948.6110694615174;950.8716323296405;988.2341185581232;5614.29821272341;6162.26052873649;6589.505787065893;6694.665266736741;6873.4157596603845;972.1493693018608;1006.8220182386625;1013.9391636501686;1019.7807471393686;1038.4859494550246;4158.189153115174;4194.6853985951175;4227.7040607157205;4236.322188449833;4427.867163985085;13263.867361326456;13618.591442256748;13620.727585448236;13632.931835340958;13661.658458538615;10671.73320666974;13455.12541684807;13544.45822167122;13555.457781688621;13632.591022269347;8326.916730832614;9863;10104.949475252577;10117.696469106086;10170.898291017143;5465.6447330923365;6700.731970721312;6722.932770672426;7007.299270072952;7027;11760.294382337088;12096.516139354328;12116;12137;12151.574694885636;11696.532138714356;12117.333546655018;12211.816822747489;12220.144589878622;12271;8024.919750802417;8321.12628174033;8934.28525717928;9096;9151.63393464254;8581.742547723577;10129.189664826648;10156.136728378171;10276.126529245097;10280.794384112425;7457.664405101892;7780.766577002699;8952.28381729473;8984.59569319369;9056.411333263317;8752.64989400417;8769.12308769118;8794.29645628361;8820.514871681862;9040.41237319572;7762.61186940662;7827.412944029106;7851.685932562634;7909.9209007909185;7920.960395197987;5883.794067207627;5894.528437724886;5994.520438365008;6014.1580178775885;6021;5979.5814292999075;6004.939950600438;6051.304100028553;6129.417705317987;6176.227971503562 09:56:56 INFO - PID 1940 | |4;prototype.html;318.8581081418768;377.7638975640225;378.2944807933202;387.103854576655;426.8134585851333;5472.288602481677;6096.969515152418;6115.9999999999945;6121.173641558388;6131.632102073879;5408.594355423349;5644.943550564493;5704.429557044304;5930.347661757214;5982;3939.763614183144;3954.051027753333;4346.348047792841;4588.151192029485;4590.74750888701;3949.64453199212;4593.931091033621;4605.953940460603;4613.331066995283;4633.745144017078;3835.539735231776;4453.910921781562;4474.664400169984;4582.473015603198;4595.736172552558;3941.487606611151;4525.615322697575;4529.184746745585;4532.750698711569;4553.954460455386;4025.778582177979;4029.8992525187105;4042.2521833460905;4046.5751096135073;4067.837286508536;5028.647994640377;5596.132599447093;5674.148877668342;5788.392218817048;5876.588638795243;4687.88280292992;5366.8926621467745;5385.976664433784;5417.918731219035;5425.422924111055;4961.925571116436;6358.364163583651;6399.856048223851;6439.710213040425;6478.538155500926;3432.467967465047;3931.2334094851517;4096.344584866437;4470.284754439306;4491.416115904912;6119.5104391648565;6692.063111164441;6698.63157526331;6730.226024007253;6730.899036514456;4977.925331120036;5530.806421775219;5695.259616249944;5739.278216535023;5772.249607550992;5822.679752613647;5840.386759390288;5847.573257098342;5879.647221166743;5897.380775018647;5801.796937107181;6010.639361638228;6027.186329845503;6060.575759696779;6074.635521868702;6545.21457425112;6563.540552161303;6569.37590928861;6570.178727659043;6582.967085164543;1598.4085888221432;1871.6069625378823;1952.8868544929537;2013.5804257998077;2046.6316063108784;1395.8045873577707;1469.2947385255138;1598.5284341119398;1635.9182040897908;1640.7374820028738;5734.455226753452;6019.849503762441;6041.818745437644;6057.666828324484;6058.939410605837;6325.557210995278;6714.731410743613;6760.0535924970545;6783.285616496488;6784.932150678529;5073.289739436482;5787.052070479764;6037.486813620892;6047.485963693135;6072.149899014142;3307.007897630749;3392.355452464024;3467.3412051710093;3492.877749278763;3535.540379750616;6521.543491955613;6955.7217711292;6981.476389270825;6999.32535418899;7020;5702.6293290935955;5833.88332233353;6174.845628859224;6587.419019435397;6956.269591692901;5757.6833274169485;6079.3920607939735;6941.876155729942;6971.79084627462;6981.232064472904;6559.934400655932;6700.765473208415;6701.463882889455;6709.93290067093;6761.256261811295;6237.844053898689;6328.9050664240995;6462.386073323027;6502.837429064311;6574.8356291091195;6720.865582688417;6947.131608548932;6965.756198533027;6977.406920411619;6980.301969802979;5424.267723857387;5651.802186923438;5675.659460432387;5692.2600061993335;5712.400197979152;5210.97394513025;5594.468525490072;6550.672466376757;6567.671616419065;6669.699863506251;6406.295307516077;6425.935740642533;6443.516736244706;6451.903221451588;6508.869822603616;6018.247719035121;6155.169052177898;6403.135576697273;6440.259370172303;6453.128827608401;5536.557075433875;5573.526250268773;5802.127946666427;6249.406306400884;6395.264544577247;5097.7960881565805;5828.805094955609;6158.291796443466;6330.398612132024;6482.448991835747;6338.524610654127;6348.206474190726;6348.968255158879;6353.650549219652;6368.331325210786 09:56:56 INFO - PID 1940 | |5;yui.html;654.247615242471;741.9666115024825;766.4443278622999;794.936405087593;820.5158956215831;14287.285635718212;14420.92789536052;15753.763693544577;15798.210089495537;15800.367985280604;14508.419663213459;14689.92655036726;14702.264886755645;14763.26183690814;15193.544193674208;805.0862271322047;909.9590518426687;945.054945054945;945.1824172091126;945.9621615135386;946.0633972367375;966.1690945786653;968.6173961285288;979.0405402705353;982.2240430060244;1021.1269364693192;1024.6311327921942;1025.2669341420883;1027.2706378471294;1028.511457057899;1018.3889666200295;1020.8417695257247;1022.4223313827664;1022.4529876516066;1023.268363120365;758.2228216078508;759.6429678051308;761.4936067515096;762.3481922955848;762.7673559564331;14623.634409139857;14855.777163342558;15380.384784608603;16483.67032659352;16502.504924852274;14568.562943111723;14884.553463396116;14911.552653420305;14956.775648365283;14972.625684357869;11635.418229088511;11819.704507387298;11826.34955077479;11838.526458941718;12162;3701.2042410881522;3735.869244576427;3779.527559055118;3782.924341513182;3881.7670939743707;108028.45985770099;108391;108980.455097724;111249.44375278072;113052.43473782661;10851.565937362417;11317.660470185907;11383.37391443462;11869.169158158846;11911.344876031728;17883.821161788215;23883.52232955366;24351.634725479133;24590.508189836102;24681.136160234666;7851.411144164211;7907.367410607253;8003.559804210708;8073.515589064675;8139.30815880645;4123.670106391482;4210.389493523422;4214.8198504418815;4220.53574106848;4223.387608796707;3179.1098492422157;3326.534285200074;3517.9472307915403;3524.5946716127974;3557.1462848916076;645.3127419298432;646.8770933522616;653.6894974886891;660.2572106380322;661.1702313596405;630.7322282212785;641.3137942401679;665.1751827733575;671.9395254427125;678.6674529480617;8314.6674133034;10117.645882394081;10200.28597998133;10350.792984140275;10493.632722854816;646.7962591783573;700.7407259314086;704.340299245703;717.4834119433999;737.0455260437745;4109.4246805447265;4364.8254069837485;4847.660663753574;4857.781399836945;4863.270509423614;104191.4790426043;106292.93707062985;106626;108039;110766;58503.24490265299;76926.84609730859;78516.60741696255;90987;94486;53778.73110634422;69282.30717692758;72142;76423.50925598571;76739;10621.946890265499;10818;10889.292196007234;10928.890711092787;11121.388323641953;29010.27474313159;43854.78072609616;47798;48238;49968.750156248985;64388.67805660942;66519.33701657497;68200;71955.20111997242;72936.6353168252;9641.903580964381;12081.275123492618;13270.203787772765;13280.800787987995;13367.264800436069;12260.632181034583;14257.50098746539;14402.42390304376;14487.348069336695;14547.636309092357;13442.529511467052;13467.233165958263;13586;13805.447782088604;13859.30703464843;13554.93222533881;13750.65623359424;13758.422810069254;14225;14562.27188640585;11193;11279.605213817476;13131.934340328236;13138.883194928538;13520.59438216855;9135.680251191443;10119;10796.514156862802;10902.236843420884;11175.441227938409;8881.270803021618;9887.528117970507;11006.836513625689;11131.554737810397;11254.268472549258 09:56:56 INFO - PID 1940 | __end_tp_report 09:56:56 INFO - PID 1940 | __start_cc_report 09:56:56 INFO - PID 1940 | _x_x_mozilla_cycle_collect,421 09:56:56 INFO - PID 1940 | __end_cc_report 09:56:56 INFO - PID 1940 | __startTimestamp1489856216109__endTimestamp 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | ------- Summary: start ------- 09:56:56 INFO - PID 1940 | Number of tests: 6 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#0] dojo.html Cycles:165 Average:5961.51 Median:6234.50 stddev:691.27 (11.1%) stddev-sans-first:664.68 09:56:56 INFO - PID 1940 | Values: 3448.5 3451.2 3475.4 3528.4 3531.1 5898.1 6031.8 6075.2 6079.1 6082.2 5936.2 6047.2 6054.0 6101.5 6138.7 5390.5 5438.8 5443.8 5475.9 5535.4 5379.2 5442.3 5465.9 5493.3 5527.7 5331.5 5462.8 5464.0 5493.1 5548.9 5353.0 5377.4 5423.7 5429.9 5437.9 5310.1 5375.0 5380.1 5446.8 5454.7 5017.7 5522.8 5946.8 6000.2 6030.1 6227.9 6236.4 6373.6 6412.5 6456.6 6484.7 6516.6 6526.9 6535.4 6545.1 6198.1 6202.1 6202.5 6203.0 6208.0 6374.6 6432.0 6438.0 6622.9 6633.4 6244.6 6266.2 6312.5 6330.9 6356.5 6452.8 6502.6 6509.9 6511.5 6515.2 6277.8 6382.6 6403.4 6434.6 6502.9 6264.5 6288.1 6414.0 6431.9 6438.3 5207.9 5220.1 5255.3 5261.6 5270.6 3878.8 3929.8 4061.4 4630.8 4640.5 5045.8 5186.7 6131.9 6153.9 6158.4 6300.0 6331.7 6467.7 6477.7 6515.6 6129.9 6157.0 6166.1 6169.9 6179.8 5698.7 5701.9 5705.1 5715.6 5744.5 6399.5 6477.8 6512.5 6682.6 6688.2 6468.4 6518.5 6518.8 6519.0 6542.1 6671.9 6677.7 6680.9 6681.8 6690.2 5576.6 6264.4 6312.6 6341.3 6414.3 5817.4 6444.7 6461.7 6488.3 6491.5 6279.6 6312.1 6321.2 6334.0 6336.0 6170.1 6215.8 6243.8 6262.9 6264.6 6142.0 6275.5 6303.6 6319.4 6319.7 6234.5 6264.6 6278.6 6292.7 6310.3 6223.6 6272.1 6274.5 6303.7 6308.0 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#1] ext.html Cycles:180 Average:54665.89 Median:192981.50 stddev:107197.69 (55.5%) stddev-sans-first:107421.73 09:56:56 INFO - PID 1940 | Values: 664.7 669.3 683.6 729.3 797.4 36649.0 37448.4 37461.8 37483.6 37511.8 39446.8 39875.0 40046.4 40054.0 40085.2 9089.0 9240.0 9256.6 9266.6 10651.3 10825.6 10984.8 10998.0 11004.7 11015.9 9322.8 10938.9 10967.7 11010.7 11037.4 9518.0 10919.7 10980.1 10984.6 10991.8 10632.1 10649.4 10747.4 10756.5 10782.6 37459.6 37741.4 37809.6 37842.8 37893.2 3807.7 3812.4 3847.9 3849.6 3994.3 21430.8 27645.6 27946.7 28422.9 28451.7 3082.3 3164.2 3239.5 3273.7 3283.6 338077.3 342429.0 342746.0 344309.0 350754.0 52349.5 52427.0 52502.7 52541.0 52588.2 45155.5 50262.0 54590.5 55021.7 55075.7 14890.9 14918.6 14991.7 15032.6 15045.3 12215.1 12265.5 12266.9 12276.4 12288.3 11431.7 12261.8 13713.1 14153.9 14182.4 6014.2 6033.9 6066.9 6074.5 6097.7 974.9 1117.1 1119.2 1123.5 1129.7 23630.5 24475.0 25756.5 25811.0 26069.7 1206.2 1212.4 1232.1 1258.5 1267.9 11231.8 13444.2 13457.8 13618.9 13773.8 352912.0 402800.0 408669.0 409529.9 409569.0 40131.0 42221.8 43172.0 55918.2 58819.4 1274.8 1365.6 1404.2 1407.5 1418.2 24418.9 26377.9 27260.5 28687.7 28695.1 45775.0 45924.3 46092.3 46160.8 46205.8 459895.7 463923.0 467009.0 467028.0 467298.0 33449.5 33844.3 34848.7 35214.3 35243.0 44430.3 44610.8 44674.8 45935.9 46131.1 36145.5 36859.0 37895.0 37904.8 38093.6 36054.0 36639.0 36660.8 36868.0 37479.4 35209.0 36259.9 36283.5 36856.3 36869.3 31247.7 32645.7 32652.0 32825.7 32998.7 21334.6 23742.9 24350.8 24684.8 25414.3 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#2] jquery.html Cycles:180 Average:39070.53 Median:22527.72 stddev:106771.75 (474.0%) stddev-sans-first:107031.80 09:56:56 INFO - PID 1940 | Values: 405.6 406.9 423.6 433.4 437.7 20399.6 20909.0 21259.6 21789.2 23372.4 22410.9 22961.2 23321.7 23324.5 23486.1 6569.2 6685.0 6879.5 6943.2 6968.6 6619.3 6874.1 6877.9 6883.1 6887.6 6488.8 6679.6 6776.6 6874.9 6888.9 6709.6 6769.3 6776.1 6795.2 6800.7 4979.5 6601.6 6633.4 6638.8 6738.2 20056.5 25375.5 25588.6 26668.0 27976.9 2776.0 2866.7 2928.0 2963.9 3003.5 14453.6 15450.5 16064.0 16324.9 16697.8 1718.6 1947.7 2005.8 2011.8 2021.0 119900.0 120923.0 156563.2 157870.0 158066.0 21428.0 21725.7 21954.3 22118.9 22286.7 28108.0 35288.5 35842.6 35921.8 35962.5 10062.6 10308.2 10547.7 10751.4 10882.5 8605.4 8617.3 8625.5 8757.4 8759.3 10765.6 10789.7 10792.7 10962.9 10988.7 4358.6 4389.7 4404.4 4426.2 4431.6 768.5 917.1 922.8 924.8 960.5 17107.0 22644.5 22764.3 23199.8 23220.9 913.8 927.6 942.9 964.0 970.4 11932.8 12139.1 12265.2 12304.1 12343.4 502354.0 660224.0 664696.0 665100.7 684614.0 116429.0 116511.0 141990.0 142124.0 143673.0 1206.3 1208.8 1250.4 1251.0 1254.1 19134.6 19689.6 19951.3 19969.7 20144.7 56036.7 56720.1 56895.7 57267.9 57709.8 79266.0 82389.0 84033.7 84837.0 92568.9 10468.7 10930.7 11015.5 11856.1 12173.8 11788.6 13770.9 13782.1 13953.9 14145.2 11397.9 11664.1 11819.0 11866.4 11871.9 10487.7 11584.1 11618.8 11792.2 12069.5 9066.6 11385.9 12051.2 12099.3 12202.2 11780.0 11934.8 12075.2 12215.1 12256.8 11499.9 11507.9 11542.5 11807.4 11813.1 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#3] mootools.html Cycles:180 Average:6591.25 Median:2384.39 stddev:4106.03 (172.2%) stddev-sans-first:4099.07 09:56:56 INFO - PID 1940 | Values: 1407.6 1410.7 1561.0 1598.7 1663.5 9388.3 9642.3 10375.9 10736.1 10798.2 9000.7 10328.3 10652.6 10673.3 10727.1 2320.8 2376.5 2380.0 2380.7 2388.1 2315.5 2346.7 2360.8 2363.8 2366.4 2322.2 2325.4 2336.0 2342.5 2350.4 2306.6 2307.3 2311.2 2316.5 2324.7 1916.4 2298.0 2298.7 2350.5 2366.9 9267.2 10916.9 10999.1 10999.5 11012.6 4587.8 5515.3 5697.7 5763.9 5768.8 2870.5 2923.4 3443.7 3652.3 3668.4 1152.4 1200.6 1215.3 1228.1 1243.4 13976.7 14063.9 15194.7 15234.9 15266.5 4112.6 4478.5 4576.4 4594.4 4806.8 11043.6 12444.3 12644.9 12780.5 12780.7 5830.4 5916.9 5923.2 5948.3 5953.6 4499.8 4898.2 5175.5 5229.4 5238.8 3935.6 3994.8 4069.6 4117.0 4129.2 1146.6 1147.8 1148.1 1157.6 1162.2 855.7 939.9 948.6 950.9 988.2 5614.3 6162.3 6589.5 6694.7 6873.4 972.1 1006.8 1013.9 1019.8 1038.5 4158.2 4194.7 4227.7 4236.3 4427.9 13263.9 13618.6 13620.7 13632.9 13661.7 10671.7 13455.1 13544.5 13555.5 13632.6 8326.9 9863.0 10104.9 10117.7 10170.9 5465.6 6700.7 6722.9 7007.3 7027.0 11760.3 12096.5 12116.0 12137.0 12151.6 11696.5 12117.3 12211.8 12220.1 12271.0 8024.9 8321.1 8934.3 9096.0 9151.6 8581.7 10129.2 10156.1 10276.1 10280.8 7457.7 7780.8 8952.3 8984.6 9056.4 8752.6 8769.1 8794.3 8820.5 9040.4 7762.6 7827.4 7851.7 7909.9 7921.0 5883.8 5894.5 5994.5 6014.2 6021.0 5979.6 6004.9 6051.3 6129.4 6176.2 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#4] prototype.html Cycles:180 Average:5364.57 Median:5862.08 stddev:1553.09 (26.5%) stddev-sans-first:1510.56 09:56:56 INFO - PID 1940 | Values: 318.9 377.8 378.3 387.1 426.8 5472.3 6097.0 6116.0 6121.2 6131.6 5408.6 5644.9 5704.4 5930.3 5982.0 3939.8 3954.1 4346.3 4588.2 4590.7 3949.6 4593.9 4606.0 4613.3 4633.7 3835.5 4453.9 4474.7 4582.5 4595.7 3941.5 4525.6 4529.2 4532.8 4554.0 4025.8 4029.9 4042.3 4046.6 4067.8 5028.6 5596.1 5674.1 5788.4 5876.6 4687.9 5366.9 5386.0 5417.9 5425.4 4961.9 6358.4 6399.9 6439.7 6478.5 3432.5 3931.2 4096.3 4470.3 4491.4 6119.5 6692.1 6698.6 6730.2 6730.9 4977.9 5530.8 5695.3 5739.3 5772.2 5822.7 5840.4 5847.6 5879.6 5897.4 5801.8 6010.6 6027.2 6060.6 6074.6 6545.2 6563.5 6569.4 6570.2 6583.0 1598.4 1871.6 1952.9 2013.6 2046.6 1395.8 1469.3 1598.5 1635.9 1640.7 5734.5 6019.8 6041.8 6057.7 6058.9 6325.6 6714.7 6760.1 6783.3 6784.9 5073.3 5787.1 6037.5 6047.5 6072.1 3307.0 3392.4 3467.3 3492.9 3535.5 6521.5 6955.7 6981.5 6999.3 7020.0 5702.6 5833.9 6174.8 6587.4 6956.3 5757.7 6079.4 6941.9 6971.8 6981.2 6559.9 6700.8 6701.5 6709.9 6761.3 6237.8 6328.9 6462.4 6502.8 6574.8 6720.9 6947.1 6965.8 6977.4 6980.3 5424.3 5651.8 5675.7 5692.3 5712.4 5211.0 5594.5 6550.7 6567.7 6669.7 6406.3 6425.9 6443.5 6451.9 6508.9 6018.2 6155.2 6403.1 6440.3 6453.1 5536.6 5573.5 5802.1 6249.4 6395.3 5097.8 5828.8 6158.3 6330.4 6482.4 6338.5 6348.2 6349.0 6353.7 6368.3 09:56:56 INFO - PID 1940 | 09:56:56 INFO - PID 1940 | [#5] yui.html Cycles:180 Average:20153.47 Median:20883.67 stddev:29107.83 (139.4%) stddev-sans-first:29152.67 09:56:56 INFO - PID 1940 | Values: 654.2 742.0 766.4 794.9 820.5 14287.3 14420.9 15753.8 15798.2 15800.4 14508.4 14689.9 14702.3 14763.3 15193.5 805.1 910.0 945.1 945.2 946.0 946.1 966.2 968.6 979.0 982.2 1021.1 1024.6 1025.3 1027.3 1028.5 1018.4 1020.8 1022.4 1022.5 1023.3 758.2 759.6 761.5 762.3 762.8 14623.6 14855.8 15380.4 16483.7 16502.5 14568.6 14884.6 14911.6 14956.8 14972.6 11635.4 11819.7 11826.3 11838.5 12162.0 3701.2 3735.9 3779.5 3782.9 3881.8 108028.5 108391.0 108980.5 111249.4 113052.4 10851.6 11317.7 11383.4 11869.2 11911.3 17883.8 23883.5 24351.6 24590.5 24681.1 7851.4 7907.4 8003.6 8073.5 8139.3 4123.7 4210.4 4214.8 4220.5 4223.4 3179.1 3326.5 3517.9 3524.6 3557.1 645.3 646.9 653.7 660.3 661.2 630.7 641.3 665.2 671.9 678.7 8314.7 10117.6 10200.3 10350.8 10493.6 646.8 700.7 704.3 717.5 737.0 4109.4 4364.8 4847.7 4857.8 4863.3 104191.5 106292.9 106626.0 108039.0 110766.0 58503.2 76926.8 78516.6 90987.0 94486.0 53778.7 69282.3 72142.0 76423.5 76739.0 10621.9 10818.0 10889.3 10928.9 11121.4 29010.3 43854.8 47798.0 48238.0 49968.8 64388.7 66519.3 68200.0 71955.2 72936.6 9641.9 12081.3 13270.2 13280.8 13367.3 12260.6 14257.5 14402.4 14487.3 14547.6 13442.5 13467.2 13586.0 13805.4 13859.3 13554.9 13750.7 13758.4 14225.0 14562.3 11193.0 11279.6 13131.9 13138.9 13520.6 9135.7 10119.0 10796.5 10902.2 11175.4 8881.3 9887.5 11006.8 11131.6 11254.3 09:56:56 INFO - PID 1940 | -------- Summary: end -------- 09:56:56 INFO - PID 1940 | 09:56:56 INFO - TEST-INFO | 1940: exit 0 09:56:56 INFO - TEST-OK | dromaeo_css | took 1116897ms 09:56:56 INFO - TEST-START | kraken 09:56:56 INFO - operating with platform_type : mac_ 09:56:56 INFO - Initialising browser for kraken test... 09:56:56 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpsq5BU8/profile http://localhost:53415/getInfo.html) 09:56:59 INFO - PID 1946 | 2017-03-18 09:56:59.562 plugin-container[1947:20030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 09:56:59 INFO - PID 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:59 INFO - PID 1946 | 2017-03-18 09:56:59.565 plugin-container[1947:20030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 09:56:59 INFO - PID 1946 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:59 INFO - PID 1946 | 2017-03-18 09:56:59.565 plugin-container[1947:20030] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:59 INFO - PID 1946 | __metrics Screen width/height:1600/1200 09:56:59 INFO - PID 1946 | colorDepth:24 09:56:59 INFO - PID 1946 | Browser inner width/height: 1024/689 09:56:59 INFO - PID 1946 | __metrics 09:56:59 ERROR - PID 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:57:00 INFO - TEST-INFO | 1946: exit 0 09:57:00 INFO - Browser initialized. 09:57:00 INFO - Running cycle 1/1 for kraken test... 09:57:00 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpsq5BU8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:03 INFO - PID 1948 | 2017-03-18 09:57:03.220 plugin-container[1949:20152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:57:03 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:03 INFO - PID 1948 | 2017-03-18 09:57:03.220 plugin-container[1949:20152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9113, name = 'com.apple.CFPasteboardClient' 09:57:03 INFO - PID 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:03 INFO - PID 1948 | 2017-03-18 09:57:03.220 plugin-container[1949:20152] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:12 INFO - PID 1948 | RSS: Main: 227868672 09:57:12 INFO - PID 1948 | 09:57:15 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53415/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:16 INFO - PID 1948 | RSS: Main: 241893376 09:57:16 INFO - PID 1948 | 09:57:19 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53415/tests/kraken/driver.html?testName=audio-dft) 09:57:19 INFO - PID 1948 | RSS: Main: 250171392 09:57:19 INFO - PID 1948 | 09:57:23 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53415/tests/kraken/driver.html?testName=audio-fft) 09:57:24 INFO - PID 1948 | RSS: Main: 252833792 09:57:24 INFO - PID 1948 | 09:57:26 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53415/tests/kraken/driver.html?testName=audio-oscillator) 09:57:26 INFO - PID 1948 | RSS: Main: 255082496 09:57:26 INFO - PID 1948 | 09:57:28 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53415/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:29 INFO - PID 1948 | RSS: Main: 255410176 09:57:29 INFO - PID 1948 | 09:57:32 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53415/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:33 INFO - PID 1948 | RSS: Main: 260718592 09:57:33 INFO - PID 1948 | 09:57:36 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53415/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:36 INFO - PID 1948 | RSS: Main: 261812224 09:57:36 INFO - PID 1948 | 09:57:39 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53415/tests/kraken/driver.html?testName=json-parse-financial) 09:57:40 INFO - PID 1948 | RSS: Main: 261431296 09:57:40 INFO - PID 1948 | 09:57:42 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53415/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:43 INFO - PID 1948 | RSS: Main: 261099520 09:57:43 INFO - PID 1948 | 09:57:44 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:45 INFO - PID 1948 | RSS: Main: 261136384 09:57:45 INFO - PID 1948 | 09:57:47 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:48 INFO - PID 1948 | RSS: Main: 261165056 09:57:48 INFO - PID 1948 | 09:57:51 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:52 INFO - PID 1948 | RSS: Main: 261509120 09:57:52 INFO - PID 1948 | 09:57:55 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:55 INFO - PID 1948 | RSS: Main: 261312512 09:57:55 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | Cycle 1(1): loaded http://localhost:53415/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53415/tests/kraken/driver.html?testName=ai-astar) 09:57:57 INFO - PID 1948 | RSS: Main: 262946816 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | __start_tp_report 09:57:57 INFO - PID 1948 | _x_x_mozilla_page_load 09:57:57 INFO - PID 1948 | _x_x_mozilla_page_load_details 09:57:57 INFO - PID 1948 | |i|pagename|runs| 09:57:57 INFO - PID 1948 | |0;ai-astar;109;122;118;118;119;118;119;127;172;169 09:57:57 INFO - PID 1948 | |1;audio-beat-detection;156;144;113;156;117;114;114;115;138;113 09:57:57 INFO - PID 1948 | |2;audio-dft;276;252;215;227;234;194;274;280;195;223 09:57:57 INFO - PID 1948 | |3;audio-fft;82;59;61;85;60;61;59;58;75;58 09:57:57 INFO - PID 1948 | |4;audio-oscillator;83;70;93;69;70;71;85;70;78;70 09:57:57 INFO - PID 1948 | |5;imaging-gaussian-blur;88;89;88;88;113;137;90;107;89;90 09:57:57 INFO - PID 1948 | |6;imaging-darkroom;96;114;96;125;143;121;126;123;99;98 09:57:57 INFO - PID 1948 | |7;imaging-desaturate;112;103;89;91;113;143;90;88;91;92 09:57:57 INFO - PID 1948 | |8;json-parse-financial;61;91;91;103;101;96;67;66;67;68 09:57:57 INFO - PID 1948 | |9;json-stringify-tinderbox;57;57;61;56;56;80;79;85;86;85 09:57:57 INFO - PID 1948 | |10;stanford-crypto-aes;82;85;71;92;107;74;106;106;104;74 09:57:57 INFO - PID 1948 | |11;stanford-crypto-ccm;144;158;157;137;140;156;145;155;179;147 09:57:57 INFO - PID 1948 | |12;stanford-crypto-pbkdf2;186;193;187;187;191;177;185;185;184;252 09:57:57 INFO - PID 1948 | |13;stanford-crypto-sha256-iterative;67;69;68;68;71;66;66;70;69;68 09:57:57 INFO - PID 1948 | __end_tp_report 09:57:57 INFO - PID 1948 | __start_cc_report 09:57:57 INFO - PID 1948 | _x_x_mozilla_cycle_collect,989 09:57:57 INFO - PID 1948 | __end_cc_report 09:57:57 INFO - PID 1948 | __startTimestamp1489856277908__endTimestamp 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | ------- Summary: start ------- 09:57:57 INFO - PID 1948 | Number of tests: 14 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#0] ai-astar Cycles:10 Average:129.10 Median:120.50 stddev:22.27 (18.5%) stddev-sans-first:22.41 09:57:57 INFO - PID 1948 | Values: 109.0 122.0 118.0 118.0 119.0 118.0 119.0 127.0 172.0 169.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:127.50 stddev:18.43 (14.5%) stddev-sans-first:16.53 09:57:57 INFO - PID 1948 | Values: 156.0 144.0 113.0 156.0 117.0 114.0 114.0 115.0 138.0 113.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#2] audio-dft Cycles:10 Average:237.00 Median:243.00 stddev:32.26 (13.3%) stddev-sans-first:30.98 09:57:57 INFO - PID 1948 | Values: 276.0 252.0 215.0 227.0 234.0 194.0 274.0 280.0 195.0 223.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#3] audio-fft Cycles:10 Average:65.80 Median:61.00 stddev:10.59 (17.4%) stddev-sans-first:9.47 09:57:57 INFO - PID 1948 | Values: 82.0 59.0 61.0 85.0 60.0 61.0 59.0 58.0 75.0 58.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:74.50 stddev:8.44 (11.3%) stddev-sans-first:8.55 09:57:57 INFO - PID 1948 | Values: 83.0 70.0 93.0 69.0 70.0 71.0 85.0 70.0 78.0 70.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:88.50 stddev:16.39 (18.5%) stddev-sans-first:16.99 09:57:57 INFO - PID 1948 | Values: 88.0 89.0 88.0 88.0 113.0 137.0 90.0 107.0 89.0 90.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#6] imaging-darkroom Cycles:10 Average:114.10 Median:119.50 stddev:16.21 (13.6%) stddev-sans-first:15.81 09:57:57 INFO - PID 1948 | Values: 96.0 114.0 96.0 125.0 143.0 121.0 126.0 123.0 99.0 98.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#7] imaging-desaturate Cycles:10 Average:101.20 Median:89.50 stddev:17.47 (19.5%) stddev-sans-first:18.09 09:57:57 INFO - PID 1948 | Values: 112.0 103.0 89.0 91.0 113.0 143.0 90.0 88.0 91.0 92.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#8] json-parse-financial Cycles:10 Average:81.10 Median:67.50 stddev:16.65 (24.7%) stddev-sans-first:15.99 09:57:57 INFO - PID 1948 | Values: 61.0 91.0 91.0 103.0 101.0 96.0 67.0 66.0 67.0 68.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:79.50 stddev:13.73 (17.3%) stddev-sans-first:13.71 09:57:57 INFO - PID 1948 | Values: 57.0 57.0 61.0 56.0 56.0 80.0 79.0 85.0 86.0 85.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:74.00 stddev:14.77 (20.0%) stddev-sans-first:15.37 09:57:57 INFO - PID 1948 | Values: 82.0 85.0 71.0 92.0 107.0 74.0 106.0 106.0 104.0 74.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:155.50 stddev:12.12 (7.8%) stddev-sans-first:12.52 09:57:57 INFO - PID 1948 | Values: 144.0 158.0 157.0 137.0 140.0 156.0 145.0 155.0 179.0 147.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.70 Median:187.00 stddev:21.27 (11.4%) stddev-sans-first:22.42 09:57:57 INFO - PID 1948 | Values: 186.0 193.0 187.0 187.0 191.0 177.0 185.0 185.0 184.0 252.0 09:57:57 INFO - PID 1948 | 09:57:57 INFO - PID 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.62 (2.4%) stddev-sans-first:1.66 09:57:57 INFO - PID 1948 | Values: 67.0 69.0 68.0 68.0 71.0 66.0 66.0 70.0 69.0 68.0 09:57:57 INFO - PID 1948 | -------- Summary: end -------- 09:57:57 INFO - PID 1948 | 09:57:58 INFO - TEST-INFO | 1948: exit 0 09:57:59 INFO - TEST-OK | kraken | took 62103ms 09:57:59 INFO - SUITE-END | took 1179s 09:57:59 INFO - Completed test suite (00:19:39) 09:57:59 INFO - javascript benchmark 09:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.640921020407, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3448.5172075909377, 3451.171718787491, 3475.3744326021306, 3528.435450327948, 3531.0995696097493, 5898.085796701507, 6031.758729650809, 6075.210222671052, 6079.05774604937, 6082.239720034996, 5936.168936348904, 6047.15339852421, 6054.0, 6101.489633126343, 6138.664296166084, 5390.51485366318, 5438.755256013469, 5443.755031023614, 5475.890482190353, 5535.363433205172, 5379.166229234455, 5442.265294185294, 5465.863353416156, 5493.340799104113, 5527.723613819313, 5331.493508116722, 5462.808801691962, 5464.043792336365, 5493.066178749602, 5548.889022219553, 5352.982933242672, 5377.381601115843, 5423.674579525202, 5429.891402171974, 5437.891242175174, 5310.097283461782, 5375.0, 5380.085385484477, 5446.754896029688, 5454.699991500466, 5017.745563609098, 5522.779088836442, 5946.762129514815, 6000.219971403733, 6030.09548567714, 6227.937720622826, 6236.376362363772, 6373.5857169283845, 6412.454941329993, 6456.612603243773, 6484.740610375579, 6516.641584712839, 6526.934730652728, 6535.411812936811, 6545.083688283644, 6198.0702894566175, 6202.0696895465135, 6202.534809889276, 6203.038529028008, 6208.0, 6374.58565193261, 6432.0, 6437.985806015561, 6622.9006564901565, 6633.436157926534, 6244.625322480575, 6266.216722909637, 6312.495000399957, 6330.936690633127, 6356.45970092538, 6452.838679033061, 6502.577332473374, 6509.9023514647315, 6511.511636627177, 6515.153030106056, 6277.780277690259, 6382.553221274559, 6403.423691867754, 6434.64609446485, 6502.902456463157, 6264.498840092781, 6288.056791481223, 6414.037894315797, 6431.871362572816, 6438.291787903327, 5207.869803254949, 5220.138677118309, 5255.343082114736, 5261.579073674096, 5270.631055826132, 3878.825452854628, 3929.8231579578987, 4061.3704875743715, 4630.814767409333, 4640.489546149921, 5045.84862454127, 5186.714730689773, 6131.877362452726, 6153.907671391748, 6158.353372895871, 6300.0234963580715, 6331.715072821642, 6467.741290348427, 6477.676116194171, 6515.641639709862, 6129.892971708966, 6156.984097623931, 6166.105914642351, 6169.907451388232, 6179.752809887555, 5698.686572238484, 5701.885962280814, 5705.087186050295, 5715.571332150105, 5744.482996530249, 6399.456046236122, 6477.838054048687, 6512.511561632802, 6682.565633233824, 6688.197416309879, 6468.3855033771715, 6518.543701940818, 6518.771842985473, 6518.967405162944, 6542.116814230209, 6671.8999215010845, 6677.732890684319, 6680.933190668031, 6681.832954176184, 6690.163729533809, 5576.577972616957, 6264.404881536247, 6312.621242725268, 6341.302456729848, 6414.326495717883, 5817.447342502455, 6444.742210311535, 6461.741530338733, 6488.253850807028, 6491.545591808528, 6279.560430769802, 6312.084747711464, 6321.241451025722, 6334.018227174611, 6335.968320158369, 6170.136180934763, 6215.751369945332, 6243.843903902439, 6262.906056409249, 6264.59280146789, 6142.0, 6275.52933529978, 6303.558750887394, 6319.431251187415, 6319.684015799284, 6234.501239900706, 6264.59280146789, 6278.591891527036, 6292.748290068346, 6310.337414571404, 6223.564350495603, 6272.121902933684, 6274.466670333073, 6303.747850085945, 6307.96846015767], "value": 5915.725860957763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.6676661669165, 669.3373560175427, 683.5591043776767, 729.3217307903651, 797.3621103117505, 36649.0, 37448.43827342588, 37461.81269093651, 37483.62516374835, 37511.81244093783, 39446.80276598613, 39875.0, 40046.39930401046, 40054.0, 40085.19829603406, 9089.0, 9239.95380023099, 9256.583453744564, 9266.629334826599, 10651.25441219115, 10825.621103261388, 10984.835227471554, 10998.010179083882, 11004.724881877937, 11015.944920275388, 9322.76693082675, 10938.890611093866, 10967.725806854813, 11010.669679909615, 11037.392943388111, 9518.0, 10919.672409827717, 10980.066694330992, 10984.560617575327, 10991.835122473169, 10632.096271816905, 10649.414282214475, 10747.408892510908, 10756.462176891202, 10782.5686972521, 37459.62540374616, 37741.433878491844, 37809.621903780884, 37842.81078594617, 37893.242135157416, 3807.676347510465, 3812.3519001769764, 3847.9230415391817, 3849.5957924418094, 3994.320965435883, 21430.785692143192, 27645.585316220073, 27946.720532794818, 28422.857885710648, 28451.715482845113, 3082.321889184376, 3164.2247649326064, 3239.5302681111343, 3273.672632736731, 3283.6223834259126, 338077.3096134528, 342429.0, 342746.0, 344309.0, 350754.0, 52349.47650523522, 52427.0, 52502.737486312326, 52541.0, 52588.21117683238, 45155.54844451514, 50261.99476010459, 54590.45409545933, 55021.724891376085, 55075.72462137744, 14890.851091489163, 14918.627034324012, 14991.700165996837, 15032.624184395478, 15045.322960466805, 12215.083868709882, 12265.509379624715, 12266.938665306616, 12276.386180691108, 12288.262704237774, 11431.657050288504, 12261.816072758917, 13713.108647937852, 14153.929230353988, 14182.439931607514, 6014.1580178775, 6033.909491357633, 6066.878662426815, 6074.544409169242, 6097.725602347905, 974.9268804839666, 1117.1230583991528, 1119.2445099557765, 1123.455124264731, 1129.740159763259, 23630.527389452116, 24475.0, 25756.484870302487, 25810.96756129771, 26069.73930260673, 1206.1858245684127, 1212.4362171590076, 1232.0512310568402, 1258.5406326690875, 1267.8985681145487, 11231.820658830868, 13444.193348399127, 13457.798133028013, 13618.86381136176, 13773.793393099111, 352912.0, 402800.0, 408669.0, 409529.8570521445, 409569.0, 40131.0, 42221.78889105596, 43172.0, 55918.16122758162, 58819.41180588225, 1274.7698470975554, 1365.5630198336437, 1404.1855723680242, 1407.4862675123711, 1418.1916307704716, 24418.87790561036, 26377.944882204498, 27260.454790904467, 28687.713122868503, 28695.13914582566, 45775.0, 45924.311135333664, 46092.30861537012, 46160.76919615381, 46205.76897115493, 459895.70052149525, 463923.0, 467009.0, 467028.0, 467298.0, 33449.49825752567, 33844.3231135371, 34848.65151348454, 35214.29571408609, 35243.0, 44430.3335449975, 44610.77694611506, 44674.77662611666, 45935.85160371017, 46131.077378452916, 36145.457818133276, 36859.0, 37895.0, 37904.81047594855, 38093.61906381012, 36054.0, 36638.9755966219, 36660.81669591635, 36868.0, 37479.43780843235, 35209.0, 36259.92361069399, 36283.45574816327, 36856.26287474182, 36869.34087965994, 31247.687523125387, 32645.673543264264, 32652.0, 32825.67174328226, 32998.67001329956, 21334.559687280263, 23742.881285594154, 24350.756492435557, 24684.753152468245, 25414.271220831808], "value": 18926.548509394088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.564018679919, 406.8942075060487, 423.5870026723945, 433.36079283057495, 437.66293971915786, 20399.592008159845, 20909.0, 21259.57480850382, 21789.237376691817, 23372.41568960777, 22410.88794556029, 22961.205066073802, 23321.650175247385, 23324.5335093298, 23486.060557577675, 6569.244536878273, 6685.0, 6879.5184337096425, 6943.1668199816095, 6968.616726080064, 6619.30497297784, 6874.0720002799735, 6877.931220687804, 6883.139607549057, 6887.552309099918, 6488.837779055514, 6679.632620205887, 6776.593404395752, 6874.931250687504, 6888.862222755542, 6709.597424154542, 6769.255381907986, 6776.119104516381, 6795.150606174228, 6800.65996700163, 4979.502049794992, 6601.63690996995, 6633.369829866155, 6638.800835974928, 6738.157730283715, 20056.495762817845, 25375.492490150093, 25588.616170757454, 26668.0, 27976.860115699496, 2775.9722402775915, 2866.656001279839, 2927.985360073186, 2963.9110826675233, 3003.4743919813945, 14453.638659033502, 15450.459233926758, 16064.036157830567, 16324.918375408166, 16697.83302166975, 1718.5531761741913, 1947.6981067934494, 2005.7593088829433, 2011.7585889693185, 2021.0, 119900.0, 120923.0, 156563.21718391334, 157870.0, 158066.0, 21428.0, 21725.67411488829, 21954.34136975893, 22118.88940555287, 22286.665700014513, 28108.0, 35288.470672939926, 35842.64157358393, 35921.820390897876, 35962.460563091576, 10062.597496100074, 10308.175345972304, 10547.736306592396, 10751.38729190612, 10882.455877206108, 8605.397622166389, 8617.310615150773, 8625.5437680321, 8757.386982911263, 8759.34304927133, 10765.623203187852, 10789.730256743644, 10792.65091863517, 10962.890371096346, 10988.725281868017, 4358.607725304738, 4389.714668546598, 4404.383386325917, 4426.18115648606, 4431.645468362523, 768.5273556762662, 917.1241464401614, 922.8292765838207, 924.7826760711228, 960.5293406230896, 17107.0, 22644.547109057727, 22764.317070487912, 23199.840007999534, 23220.88389558075, 913.8400779863497, 927.5640448988964, 942.9104235097655, 963.9903600963901, 970.4274478057838, 11932.76134477323, 12139.091262445614, 12265.202761820632, 12304.138710290374, 12343.444544995496, 502354.0, 660224.0, 664696.0, 665100.6744966244, 684614.0, 116429.0, 116511.0, 141990.0, 142124.0, 143673.0, 1206.2762342594374, 1208.7824191645589, 1250.3623152192265, 1250.9867007723774, 1254.0531898416637, 19134.61730765349, 19689.606207876048, 19951.301704440273, 19969.700454492904, 20144.697829532863, 56036.719816400655, 56720.14919776124, 56895.71552142213, 57267.85464290774, 57709.84580308454, 79266.0, 82389.0, 84033.68864847724, 84837.0, 92568.93207765894, 10468.709760494294, 10930.726731831768, 11015.504302306255, 11856.110791690486, 12173.817392739293, 11788.646340609796, 13770.93114534421, 13782.104163229758, 13953.93023034919, 14145.222012789396, 11397.886021139682, 11664.125190610568, 11819.0, 11866.40667966581, 11871.940640297089, 10487.685369438928, 11584.131190160602, 11618.825717614074, 11792.23350482216, 12069.456874440846, 9066.592003359996, 11385.9183377579, 12051.216670916363, 12099.33453660057, 12202.206856554296, 11779.998700110244, 11934.820977685167, 12075.215111017756, 12215.144939854124, 12256.75486490283, 11499.94250028778, 11507.906748858844, 11542.538298467967, 11807.350595717318, 11813.11401644863], "value": 11609.028649577773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.5777266819957, 1410.668492904168, 1560.9531714048583, 1598.704239715653, 1663.5342104210822, 9388.295877809158, 9642.325037247396, 10375.948120259398, 10736.08743256823, 10798.24412291139, 9000.729978100651, 10328.328658637183, 10652.573897044109, 10673.252872298943, 10727.08819750322, 2320.814334853216, 2376.4534157143867, 2379.9881000594974, 2380.690510233676, 2388.0925248405583, 2315.47901722112, 2346.683197768305, 2360.775726305998, 2363.8108951283943, 2366.4438856868624, 2322.1756276521846, 2325.3953971967326, 2336.030547322859, 2342.496363281894, 2350.4123969007746, 2306.630939049761, 2307.296274636227, 2311.2257393773107, 2316.4903721181313, 2324.732655744586, 1916.434651777729, 2298.0463107810237, 2298.678185054094, 2350.471143992605, 2366.8934897929635, 9267.212286955617, 10916.945415272952, 10999.065079468255, 10999.505022273937, 11012.614558490495, 4587.839425620121, 5515.25544051556, 5697.6866272355, 5763.942360576424, 5768.769249230026, 2870.468963241807, 2923.35686149049, 3443.741719371032, 3652.3425783358985, 3668.3580373434543, 1152.400751609167, 1200.5857978997233, 1215.3254943506352, 1228.103484456343, 1243.4155946705034, 13976.720465590632, 14063.859361406357, 15194.696106077927, 15234.93355465129, 15266.465673701477, 4112.568180341081, 4478.48497422794, 4576.382188404589, 4594.448666160015, 4806.759662016885, 11043.619547556555, 12444.253344799341, 12644.873551264554, 12780.488780448864, 12780.680482987813, 5830.416958304136, 5916.940830591639, 5923.17075609415, 5948.2862056553495, 5953.613015154001, 4499.752513611717, 4898.191798353232, 5175.508326708957, 5229.372475302988, 5238.764255608507, 3935.58676338986, 3994.840206391712, 4069.593040695907, 4116.958830411658, 4129.236091323055, 1146.5528443906717, 1147.770445910821, 1148.1446322489799, 1157.6121999130385, 1162.1690491298762, 855.6791203298762, 939.8778158839307, 948.6110694615174, 950.8716323296405, 988.2341185581232, 5614.29821272341, 6162.26052873649, 6589.505787065893, 6694.665266736741, 6873.4157596603845, 972.1493693018608, 1006.8220182386625, 1013.9391636501686, 1019.7807471393686, 1038.4859494550246, 4158.189153115174, 4194.6853985951175, 4227.7040607157205, 4236.322188449833, 4427.867163985085, 13263.867361326456, 13618.591442256748, 13620.727585448236, 13632.931835340958, 13661.658458538615, 10671.73320666974, 13455.12541684807, 13544.45822167122, 13555.457781688621, 13632.591022269347, 8326.916730832614, 9863.0, 10104.949475252577, 10117.696469106086, 10170.898291017143, 5465.6447330923365, 6700.731970721312, 6722.932770672426, 7007.299270072952, 7027.0, 11760.294382337088, 12096.516139354328, 12116.0, 12137.0, 12151.574694885636, 11696.532138714356, 12117.333546655018, 12211.816822747489, 12220.144589878622, 12271.0, 8024.919750802417, 8321.12628174033, 8934.28525717928, 9096.0, 9151.63393464254, 8581.742547723577, 10129.189664826648, 10156.136728378171, 10276.126529245097, 10280.794384112425, 7457.664405101892, 7780.766577002699, 8952.28381729473, 8984.59569319369, 9056.411333263317, 8752.64989400417, 8769.12308769118, 8794.29645628361, 8820.514871681862, 9040.41237319572, 7762.61186940662, 7827.412944029106, 7851.685932562634, 7909.9209007909185, 7920.960395197987, 5883.794067207627, 5894.528437724886, 5994.520438365008, 6014.1580178775885, 6021.0, 5979.5814292999075, 6004.939950600438, 6051.304100028553, 6129.417705317987, 6176.227971503562], "value": 5057.981141419125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8581081418768, 377.7638975640225, 378.2944807933202, 387.103854576655, 426.8134585851333, 5472.288602481677, 6096.969515152418, 6115.9999999999945, 6121.173641558388, 6131.632102073879, 5408.594355423349, 5644.943550564493, 5704.429557044304, 5930.347661757214, 5982.0, 3939.763614183144, 3954.051027753333, 4346.348047792841, 4588.151192029485, 4590.74750888701, 3949.64453199212, 4593.931091033621, 4605.953940460603, 4613.331066995283, 4633.745144017078, 3835.539735231776, 4453.910921781562, 4474.664400169984, 4582.473015603198, 4595.736172552558, 3941.487606611151, 4525.615322697575, 4529.184746745585, 4532.750698711569, 4553.954460455386, 4025.778582177979, 4029.8992525187105, 4042.2521833460905, 4046.5751096135073, 4067.837286508536, 5028.647994640377, 5596.132599447093, 5674.148877668342, 5788.392218817048, 5876.588638795243, 4687.88280292992, 5366.8926621467745, 5385.976664433784, 5417.918731219035, 5425.422924111055, 4961.925571116436, 6358.364163583651, 6399.856048223851, 6439.710213040425, 6478.538155500926, 3432.467967465047, 3931.2334094851517, 4096.344584866437, 4470.284754439306, 4491.416115904912, 6119.5104391648565, 6692.063111164441, 6698.63157526331, 6730.226024007253, 6730.899036514456, 4977.925331120036, 5530.806421775219, 5695.259616249944, 5739.278216535023, 5772.249607550992, 5822.679752613647, 5840.386759390288, 5847.573257098342, 5879.647221166743, 5897.380775018647, 5801.796937107181, 6010.639361638228, 6027.186329845503, 6060.575759696779, 6074.635521868702, 6545.21457425112, 6563.540552161303, 6569.37590928861, 6570.178727659043, 6582.967085164543, 1598.4085888221432, 1871.6069625378823, 1952.8868544929537, 2013.5804257998077, 2046.6316063108784, 1395.8045873577707, 1469.2947385255138, 1598.5284341119398, 1635.9182040897908, 1640.7374820028738, 5734.455226753452, 6019.849503762441, 6041.818745437644, 6057.666828324484, 6058.939410605837, 6325.557210995278, 6714.731410743613, 6760.0535924970545, 6783.285616496488, 6784.932150678529, 5073.289739436482, 5787.052070479764, 6037.486813620892, 6047.485963693135, 6072.149899014142, 3307.007897630749, 3392.355452464024, 3467.3412051710093, 3492.877749278763, 3535.540379750616, 6521.543491955613, 6955.7217711292, 6981.476389270825, 6999.32535418899, 7020.0, 5702.6293290935955, 5833.88332233353, 6174.845628859224, 6587.419019435397, 6956.269591692901, 5757.6833274169485, 6079.3920607939735, 6941.876155729942, 6971.79084627462, 6981.232064472904, 6559.934400655932, 6700.765473208415, 6701.463882889455, 6709.93290067093, 6761.256261811295, 6237.844053898689, 6328.9050664240995, 6462.386073323027, 6502.837429064311, 6574.8356291091195, 6720.865582688417, 6947.131608548932, 6965.756198533027, 6977.406920411619, 6980.301969802979, 5424.267723857387, 5651.802186923438, 5675.659460432387, 5692.2600061993335, 5712.400197979152, 5210.97394513025, 5594.468525490072, 6550.672466376757, 6567.671616419065, 6669.699863506251, 6406.295307516077, 6425.935740642533, 6443.516736244706, 6451.903221451588, 6508.869822603616, 6018.247719035121, 6155.169052177898, 6403.135576697273, 6440.259370172303, 6453.128827608401, 5536.557075433875, 5573.526250268773, 5802.127946666427, 6249.406306400884, 6395.264544577247, 5097.7960881565805, 5828.805094955609, 6158.291796443466, 6330.398612132024, 6482.448991835747, 6338.524610654127, 6348.206474190726, 6348.968255158879, 6353.650549219652, 6368.331325210786], "value": 4908.105852271426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.247615242471, 741.9666115024825, 766.4443278622999, 794.936405087593, 820.5158956215831, 14287.285635718212, 14420.92789536052, 15753.763693544577, 15798.210089495537, 15800.367985280604, 14508.419663213459, 14689.92655036726, 14702.264886755645, 14763.26183690814, 15193.544193674208, 805.0862271322047, 909.9590518426687, 945.054945054945, 945.1824172091126, 945.9621615135386, 946.0633972367375, 966.1690945786653, 968.6173961285288, 979.0405402705353, 982.2240430060244, 1021.1269364693192, 1024.6311327921942, 1025.2669341420883, 1027.2706378471294, 1028.511457057899, 1018.3889666200295, 1020.8417695257247, 1022.4223313827664, 1022.4529876516066, 1023.268363120365, 758.2228216078508, 759.6429678051308, 761.4936067515096, 762.3481922955848, 762.7673559564331, 14623.634409139857, 14855.777163342558, 15380.384784608603, 16483.67032659352, 16502.504924852274, 14568.562943111723, 14884.553463396116, 14911.552653420305, 14956.775648365283, 14972.625684357869, 11635.418229088511, 11819.704507387298, 11826.34955077479, 11838.526458941718, 12162.0, 3701.2042410881522, 3735.869244576427, 3779.527559055118, 3782.924341513182, 3881.7670939743707, 108028.45985770099, 108391.0, 108980.455097724, 111249.44375278072, 113052.43473782661, 10851.565937362417, 11317.660470185907, 11383.37391443462, 11869.169158158846, 11911.344876031728, 17883.821161788215, 23883.52232955366, 24351.634725479133, 24590.508189836102, 24681.136160234666, 7851.411144164211, 7907.367410607253, 8003.559804210708, 8073.515589064675, 8139.30815880645, 4123.670106391482, 4210.389493523422, 4214.8198504418815, 4220.53574106848, 4223.387608796707, 3179.1098492422157, 3326.534285200074, 3517.9472307915403, 3524.5946716127974, 3557.1462848916076, 645.3127419298432, 646.8770933522616, 653.6894974886891, 660.2572106380322, 661.1702313596405, 630.7322282212785, 641.3137942401679, 665.1751827733575, 671.9395254427125, 678.6674529480617, 8314.6674133034, 10117.645882394081, 10200.28597998133, 10350.792984140275, 10493.632722854816, 646.7962591783573, 700.7407259314086, 704.340299245703, 717.4834119433999, 737.0455260437745, 4109.4246805447265, 4364.8254069837485, 4847.660663753574, 4857.781399836945, 4863.270509423614, 104191.4790426043, 106292.93707062985, 106626.0, 108039.0, 110766.0, 58503.24490265299, 76926.84609730859, 78516.60741696255, 90987.0, 94486.0, 53778.73110634422, 69282.30717692758, 72142.0, 76423.50925598571, 76739.0, 10621.946890265499, 10818.0, 10889.292196007234, 10928.890711092787, 11121.388323641953, 29010.27474313159, 43854.78072609616, 47798.0, 48238.0, 49968.750156248985, 64388.67805660942, 66519.33701657497, 68200.0, 71955.20111997242, 72936.6353168252, 9641.903580964381, 12081.275123492618, 13270.203787772765, 13280.800787987995, 13367.264800436069, 12260.632181034583, 14257.50098746539, 14402.42390304376, 14487.348069336695, 14547.636309092357, 13442.529511467052, 13467.233165958263, 13586.0, 13805.447782088604, 13859.30703464843, 13554.93222533881, 13750.65623359424, 13758.422810069254, 14225.0, 14562.27188640585, 11193.0, 11279.605213817476, 13131.934340328236, 13138.883194928538, 13520.59438216855, 9135.680251191443, 10119.0, 10796.514156862802, 10902.236843420884, 11175.441227938409, 8881.270803021618, 9887.528117970507, 11006.836513625689, 11131.554737810397, 11254.268472549258], "value": 7593.948693316681, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 118.0, 118.0, 119.0, 118.0, 119.0, 127.0, 172.0, 169.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 144.0, 113.0, 156.0, 117.0, 114.0, 114.0, 115.0, 138.0, 113.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 252.0, 215.0, 227.0, 234.0, 194.0, 274.0, 280.0, 195.0, 223.0], "value": 237.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 85.0, 60.0, 61.0, 59.0, 58.0, 75.0, 58.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 93.0, 69.0, 70.0, 71.0, 85.0, 70.0, 78.0, 70.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 88.0, 113.0, 137.0, 90.0, 107.0, 89.0, 90.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 114.0, 96.0, 125.0, 143.0, 121.0, 126.0, 123.0, 99.0, 98.0], "value": 114.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 103.0, 89.0, 91.0, 113.0, 143.0, 90.0, 88.0, 91.0, 92.0], "value": 101.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 91.0, 91.0, 103.0, 101.0, 96.0, 67.0, 66.0, 67.0, 68.0], "value": 81.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 61.0, 56.0, 56.0, 80.0, 79.0, 85.0, 86.0, 85.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 71.0, 92.0, 107.0, 74.0, 106.0, 106.0, 104.0, 74.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 158.0, 157.0, 137.0, 140.0, 156.0, 145.0, 155.0, 179.0, 147.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 193.0, 187.0, 187.0, 191.0, 177.0, 185.0, 185.0, 184.0, 252.0], "value": 192.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 68.0, 71.0, 66.0, 66.0, 70.0, 69.0, 68.0], "value": 68.2, "unit": "score"}]}]} 09:57:59 INFO - Return code: 0 09:57:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:59 ERROR - # TBPL SUCCESS # 09:57:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:59 INFO - 2017-03-18 09:57:59.251 screenresolution[1953:20541] starting screenresolution argv=screenresolution get 09:57:59 INFO - 2017-03-18 09:57:59.253 screenresolution[1953:20541] Display 0: 1600x1200x32@60 09:57:59 INFO - 2017-03-18 09:57:59.259 screenresolution[1954:20544] starting screenresolution argv=screenresolution list 09:57:59 INFO - Available Modes on Display 0 09:57:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:59 INFO - Intel Iris: 09:57:59 INFO - Chipset Model: Intel Iris 09:57:59 INFO - Type: GPU 09:57:59 INFO - Bus: Built-In 09:57:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:59 INFO - Vendor: Intel (0x8086) 09:57:59 INFO - Device ID: 0x0a2e 09:57:59 INFO - Revision ID: 0x0009 09:57:59 INFO - Displays: 09:57:59 INFO - Display: 09:57:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:59 INFO - Display Serial Number: 1600x1200 60 09:57:59 INFO - Main Display: Yes 09:57:59 INFO - Mirror: Off 09:57:59 INFO - Online: Yes 09:57:59 INFO - Rotation: Supported 09:57:59 INFO - Return code: 0 09:57:59 INFO - Running post-action listener: _resource_record_post_action 09:57:59 INFO - [mozharness: 2017-03-18 16:57:59.501196Z] Finished run-tests step (success) 09:57:59 INFO - Running post-run listener: _resource_record_post_run 09:57:59 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 544768; Write bytes: 515748864; Read time: 7; Write time: 1622 09:57:59 INFO - TinderboxPrint: CPU usage
54.0% 09:57:59 INFO - TinderboxPrint: I/O read bytes / time
544,768 / 7 09:57:59 INFO - TinderboxPrint: I/O write bytes / time
515,748,864 / 1,622 09:57:59 INFO - TinderboxPrint: CPU idle
2,206.2 (46.0%) 09:57:59 INFO - TinderboxPrint: CPU system
960.7 (20.0%) 09:57:59 INFO - TinderboxPrint: CPU user
1,630.9 (34.0%) 09:57:59 INFO - TinderboxPrint: Swap in / out
210,403,328 / 0 09:57:59 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207708672; Write bytes: 203003392; Read time: 15653; Write time: 429 09:57:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 503808; Write bytes: 301534720; Read time: 6; Write time: 1148 09:58:00 INFO - Running post-run listener: _upload_blobber_files 09:58:00 INFO - Blob upload gear active. 09:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:58:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:02 INFO - (blobuploader) - INFO - Done attempting. 09:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:04 INFO - (blobuploader) - INFO - Done attempting. 09:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:05 INFO - (blobuploader) - INFO - Done attempting. 09:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:07 INFO - (blobuploader) - INFO - Done attempting. 09:58:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:07 INFO - Return code: 0 09:58:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b90f91d9ca062613f8807d44e1ae2c73c44afdf36f07a305f191dce7b4994e4c5efddbc91e4e5d58acc9cf58a85ce138ae7f4985b104dffb3ae3c46cc218a50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c33c03d84122002495783e4ce84f9cb37391075c95d3d2bcee322801dfb6a1db16abecf0ecdffaad202647a43e3a6703684c7e9132db87fa567d06b11b4089c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfee6cea7c984ccdf93a7ba0bd7cb78b20e8f7e53851853ebe9d468734fbe94cfc2516918b7196823ea2d7d896b4a78efa924a77885d1eed2f853d16a03138bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84c91f5ea12763b1f545f61cc9e8c0707af938c79681a95710871bd914c1d06ac55afaed4b1a720b88f7d4dcf77763168a817d6e00d1d902c7cac81628a094d4"} 09:58:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:07 INFO - Contents: 09:58:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b90f91d9ca062613f8807d44e1ae2c73c44afdf36f07a305f191dce7b4994e4c5efddbc91e4e5d58acc9cf58a85ce138ae7f4985b104dffb3ae3c46cc218a50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c33c03d84122002495783e4ce84f9cb37391075c95d3d2bcee322801dfb6a1db16abecf0ecdffaad202647a43e3a6703684c7e9132db87fa567d06b11b4089c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfee6cea7c984ccdf93a7ba0bd7cb78b20e8f7e53851853ebe9d468734fbe94cfc2516918b7196823ea2d7d896b4a78efa924a77885d1eed2f853d16a03138bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84c91f5ea12763b1f545f61cc9e8c0707af938c79681a95710871bd914c1d06ac55afaed4b1a720b88f7d4dcf77763168a817d6e00d1d902c7cac81628a094d4"} 09:58:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:07 INFO - Copying logs to upload dir... 09:58:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.131685 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-18 09:58:07.515486) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2017-03-18 09:58:07.521404) ========= 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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b90f91d9ca062613f8807d44e1ae2c73c44afdf36f07a305f191dce7b4994e4c5efddbc91e4e5d58acc9cf58a85ce138ae7f4985b104dffb3ae3c46cc218a50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c33c03d84122002495783e4ce84f9cb37391075c95d3d2bcee322801dfb6a1db16abecf0ecdffaad202647a43e3a6703684c7e9132db87fa567d06b11b4089c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfee6cea7c984ccdf93a7ba0bd7cb78b20e8f7e53851853ebe9d468734fbe94cfc2516918b7196823ea2d7d896b4a78efa924a77885d1eed2f853d16a03138bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84c91f5ea12763b1f545f61cc9e8c0707af938c79681a95710871bd914c1d06ac55afaed4b1a720b88f7d4dcf77763168a817d6e00d1d902c7cac81628a094d4"} build_url:https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010841 build_url: 'https://queue.taskcluster.net/v1/task/NLo1kPJVQpmVr1nLGWHG2g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b90f91d9ca062613f8807d44e1ae2c73c44afdf36f07a305f191dce7b4994e4c5efddbc91e4e5d58acc9cf58a85ce138ae7f4985b104dffb3ae3c46cc218a50", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c33c03d84122002495783e4ce84f9cb37391075c95d3d2bcee322801dfb6a1db16abecf0ecdffaad202647a43e3a6703684c7e9132db87fa567d06b11b4089c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bfee6cea7c984ccdf93a7ba0bd7cb78b20e8f7e53851853ebe9d468734fbe94cfc2516918b7196823ea2d7d896b4a78efa924a77885d1eed2f853d16a03138bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84c91f5ea12763b1f545f61cc9e8c0707af938c79681a95710871bd914c1d06ac55afaed4b1a720b88f7d4dcf77763168a817d6e00d1d902c7cac81628a094d4"}' ========= master_lag: 3.52 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2017-03-18 09:58:11.053004) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:58:11.053386) ========= 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.WEXBvQtoBC/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.ys7IIQ0tyx/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.005288 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-18 09:58:11.087516) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:58:11.087888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-18 09:58:11.088276) ========= ========= Total master_lag: 3.69 =========