builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0085 starttime: 1493401356.81 results: success (0) buildid: 20170428091122 builduid: 7954f5dcc6664e13953cce3160151c18 revision: 016390ad9e5c1f43f8a51ff9e212bb46e28def3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:36.809114) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:36.809563) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:36.809852) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:36.836756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:36.837029) ========= 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.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-28 10:42:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2017-04-28 10:42:37 (14.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172040 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:37.033442) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:37.033753) ========= 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.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027796 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:37.083822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 10:42:37.084148) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 016390ad9e5c1f43f8a51ff9e212bb46e28def3e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 016390ad9e5c1f43f8a51ff9e212bb46e28def3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-28 10:42:37,186 truncating revision to first 12 chars 2017-04-28 10:42:37,186 Setting DEBUG logging. 2017-04-28 10:42:37,186 attempt 1/10 2017-04-28 10:42:37,186 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/016390ad9e5c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 10:42:38,534 unpacking tar archive at: mozilla-beta-016390ad9e5c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.616138 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 10:42:38.724638) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:38.724962) ========= script_repo_revision: 016390ad9e5c1f43f8a51ff9e212bb46e28def3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:38.725367) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:38.725630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 10:42:38.747030) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 10:42:38.747387) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:42:38 INFO - MultiFileLogger online at 20170428 10:42:38 in /builds/slave/test 10:42:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:42:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:42:38 INFO - {'append_to_log': False, 10:42:38 INFO - 'base_work_dir': '/builds/slave/test', 10:42:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:42:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:42:38 INFO - 'branch': 'Mozilla-Beta', 10:42:38 INFO - 'buildbot_json_path': 'buildprops.json', 10:42:38 INFO - 'config_files': ('talos/mac_config.py',), 10:42:38 INFO - 'default_actions': ('clobber', 10:42:38 INFO - 'read-buildbot-config', 10:42:38 INFO - 'download-and-extract', 10:42:38 INFO - 'populate-webroot', 10:42:38 INFO - 'create-virtualenv', 10:42:38 INFO - 'install', 10:42:38 INFO - 'run-tests'), 10:42:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:42:38 INFO - 'download_minidump_stackwalk': True, 10:42:38 INFO - 'download_symbols': 'ondemand', 10:42:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:42:38 INFO - 'tooltool.py': '/tools/tooltool.py', 10:42:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:42:38 INFO - '/tools/misc-python/virtualenv.py')}, 10:42:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:42:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:42:38 INFO - 'gecko_profile': False, 10:42:38 INFO - 'gecko_profile_interval': 0, 10:42:38 INFO - 'installer_path': 'installer.exe', 10:42:38 INFO - 'log_level': 'info', 10:42:38 INFO - 'log_name': 'talos', 10:42:38 INFO - 'log_to_console': True, 10:42:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:42:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:42:38 INFO - 'opt_config_files': (), 10:42:38 INFO - 'pip_index': False, 10:42:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:42:38 INFO - 'cmd': ('bash', 10:42:38 INFO - '-c', 10:42:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:42:38 INFO - 'enabled': True, 10:42:38 INFO - 'halt_on_failure': False, 10:42:38 INFO - 'name': 'check_screen_resolution'},), 10:42:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:42:38 INFO - 'cmd': ('bash', 10:42:38 INFO - '-c', 10:42:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:42:38 INFO - 'enabled': True, 10:42:38 INFO - 'halt_on_failure': False, 10:42:38 INFO - 'name': 'check_screen_resolution'},), 10:42:38 INFO - 'run_cmd_checks_enabled': True, 10:42:38 INFO - 'suite': 'dromaeojs-e10s', 10:42:38 INFO - 'system_bits': '32', 10:42:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:42:38 INFO - 'title': 't-yosemite-r7-0085', 10:42:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:42:38 INFO - 'use_talos_json': True, 10:42:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:42:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:42:38 INFO - 'work_dir': 'build'} 10:42:38 INFO - [mozharness: 2017-04-28 17:42:38.960049Z] Running clobber step. 10:42:38 INFO - Running pre-action listener: _resource_record_pre_action 10:42:38 INFO - Running main action method: clobber 10:42:38 INFO - rmtree: /builds/slave/test/build 10:42:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:42:41 INFO - Running post-action listener: _resource_record_post_action 10:42:41 INFO - [mozharness: 2017-04-28 17:42:41.435936Z] Finished clobber step (success) 10:42:41 INFO - [mozharness: 2017-04-28 17:42:41.436069Z] Running read-buildbot-config step. 10:42:41 INFO - Running pre-action listener: _resource_record_pre_action 10:42:41 INFO - Running main action method: read_buildbot_config 10:42:41 INFO - Using buildbot properties: 10:42:41 INFO - { 10:42:41 INFO - "project": "", 10:42:41 INFO - "product": "firefox", 10:42:41 INFO - "script_repo_revision": "production", 10:42:41 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:42:41 INFO - "repository": "", 10:42:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:42:41 INFO - "buildid": "20170428091122", 10:42:41 INFO - "pgo_build": "False", 10:42:41 INFO - "basedir": "/builds/slave/test", 10:42:41 INFO - "buildnumber": 36, 10:42:41 INFO - "slavename": "t-yosemite-r7-0085", 10:42:41 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:42:41 INFO - "platform": "yosemite_r7", 10:42:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:42:41 INFO - "branch": "mozilla-beta", 10:42:41 INFO - "repo_path": "releases/mozilla-beta", 10:42:41 INFO - "revision": "016390ad9e5c1f43f8a51ff9e212bb46e28def3e", 10:42:41 INFO - "stage_platform": "macosx64", 10:42:41 INFO - "builduid": "7954f5dcc6664e13953cce3160151c18", 10:42:41 INFO - "slavebuilddir": "test" 10:42:41 INFO - } 10:42:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:42:41 INFO - Running post-action listener: _resource_record_post_action 10:42:41 INFO - [mozharness: 2017-04-28 17:42:41.439284Z] Finished read-buildbot-config step (success) 10:42:41 INFO - [mozharness: 2017-04-28 17:42:41.439385Z] Running download-and-extract step. 10:42:41 INFO - Running pre-action listener: _resource_record_pre_action 10:42:41 INFO - Running main action method: download_and_extract 10:42:41 INFO - mkdir: /builds/slave/test/build/tests 10:42:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:42:41 INFO - trying https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:42:41 INFO - Downloading https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:42:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:42:42 INFO - Downloaded 1165 bytes. 10:42:42 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:42:42 INFO - Using the following test package requirements: 10:42:42 INFO - {u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:42:42 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:42:42 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:42:42 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:42:42 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:42:42 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:42:42 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:42:42 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:42:42 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:42:42 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:42:42 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:42:42 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:42:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:42:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:42:42 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:42:44 INFO - Expected file size: 51225631 10:42:44 INFO - Obtained file size: 51225631 10:43:00 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:43:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:43:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:43:00 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:43:02 INFO - Expected file size: 51225631 10:43:02 INFO - Obtained file size: 51225631 10:43:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:43:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:43:17 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:43:18 INFO - Expected file size: 14404449 10:43:18 INFO - Obtained file size: 14404449 10:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:18 INFO - trying https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:43:18 INFO - Downloading https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:43:21 INFO - Downloaded 59540587 bytes. 10:43:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:43:21 INFO - mkdir: /builds/slave/test/properties 10:43:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:43:21 INFO - Writing to file /builds/slave/test/properties/build_url 10:43:21 INFO - Contents: 10:43:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:43:21 INFO - Running post-action listener: _resource_record_post_action 10:43:21 INFO - Running post-action listener: set_extra_try_arguments 10:43:21 INFO - [mozharness: 2017-04-28 17:43:21.837345Z] Finished download-and-extract step (success) 10:43:21 INFO - [mozharness: 2017-04-28 17:43:21.837477Z] Running populate-webroot step. 10:43:21 INFO - Running pre-action listener: _resource_record_pre_action 10:43:21 INFO - Running main action method: populate_webroot 10:43:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:43:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:43:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:43:21 INFO - '--tppagecycles', 10:43:21 INFO - '7'], 10:43:21 INFO - 'tests': ['tsvgm']}}, 10:43:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:43:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:43:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:43:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:43:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:43:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:43:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['damp', 'tps']}, 10:43:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'tests': ['damp', 'tps']}, 10:43:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['dromaeo_dom']}, 10:43:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:43:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['basic_compositor_video']}, 10:43:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:43:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'other-e10s': {'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['a11yr', 10:43:21 INFO - 'ts_paint', 10:43:21 INFO - 'tpaint', 10:43:21 INFO - 'sessionrestore', 10:43:21 INFO - 'sessionrestore_no_auto_restore', 10:43:21 INFO - 'tabpaint']}, 10:43:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['tsvgx', 10:43:21 INFO - 'tsvgr_opacity', 10:43:21 INFO - 'tart', 10:43:21 INFO - 'tscrollx', 10:43:21 INFO - 'cart', 10:43:21 INFO - 'tsvg_static']}, 10:43:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:43:21 INFO - 'tsvgr_opacity', 10:43:21 INFO - 'tart', 10:43:21 INFO - 'tscrollx', 10:43:21 INFO - 'cart', 10:43:21 INFO - 'tsvg_static']}, 10:43:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'talos_options': ['--disable-e10s'], 10:43:21 INFO - 'tests': ['tp5o']}, 10:43:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:43:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'talos_options': ['--disable-e10s', 10:43:21 INFO - '--xperf_path', 10:43:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:43:21 INFO - 'tests': ['tp5n']}, 10:43:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:43:21 INFO - 'talos_options': ['--xperf_path', 10:43:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:43:21 INFO - 'tests': ['tp5n']}}, 10:43:21 INFO - 'talos.zip': {'path': '', 10:43:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:43:21 INFO - Running post-action listener: _resource_record_post_action 10:43:21 INFO - [mozharness: 2017-04-28 17:43:21.856082Z] Finished populate-webroot step (success) 10:43:21 INFO - [mozharness: 2017-04-28 17:43:21.856215Z] Running create-virtualenv step. 10:43:21 INFO - Running pre-action listener: _resource_record_pre_action 10:43:21 INFO - Running main action method: create_virtualenv 10:43:21 INFO - Creating virtualenv /builds/slave/test/build/venv 10:43:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:43:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:43:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:43:21 INFO - Using real prefix '/tools/python27' 10:43:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:43:22 INFO - Installing distribute.............................................................................................................................................................................................done. 10:43:25 INFO - Installing pip.................done. 10:43:25 INFO - Return code: 0 10:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:43:26 INFO - Reading from file tmpfile_stdout 10:43:26 INFO - Output received: 10:43:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:43:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:43:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:26 INFO - 'HOME': '/Users/cltbld', 10:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:26 INFO - 'LOGNAME': 'cltbld', 10:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:26 INFO - 'MOZ_NO_REMOTE': '1', 10:43:26 INFO - 'NO_EM_RESTART': '1', 10:43:26 INFO - 'PAGER': '/bin/cat', 10:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:26 INFO - 'PWD': '/builds/slave/test', 10:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:26 INFO - 'SHELL': '/bin/bash', 10:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:26 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:26 INFO - 'USER': 'cltbld', 10:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:26 INFO - 'XPC_FLAGS': '0x0', 10:43:26 INFO - 'XPC_SERVICE_NAME': '0', 10:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:43:26 INFO - Cleaning up... 10:43:26 INFO - Return code: 0 10:43:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:43:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:43:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:26 INFO - 'HOME': '/Users/cltbld', 10:43:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:26 INFO - 'LOGNAME': 'cltbld', 10:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:26 INFO - 'MOZ_NO_REMOTE': '1', 10:43:26 INFO - 'NO_EM_RESTART': '1', 10:43:26 INFO - 'PAGER': '/bin/cat', 10:43:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:26 INFO - 'PWD': '/builds/slave/test', 10:43:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:26 INFO - 'SHELL': '/bin/bash', 10:43:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:26 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:26 INFO - 'USER': 'cltbld', 10:43:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:26 INFO - 'XPC_FLAGS': '0x0', 10:43:26 INFO - 'XPC_SERVICE_NAME': '0', 10:43:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:26 INFO - Downloading/unpacking psutil>=3.1.1 10:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:43:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:43:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:43:30 INFO - Installing collected packages: psutil 10:43:30 INFO - Running setup.py install for psutil 10:43:30 INFO - building 'psutil._psutil_osx' extension 10:43:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:43:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:43:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:43:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:43:30 INFO - building 'psutil._psutil_posix' extension 10:43:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:43:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:43:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:43:31 INFO - ^ 10:43:31 INFO - 1 warning generated. 10:43:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:43:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:43:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:43:31 INFO - Successfully installed psutil 10:43:31 INFO - Cleaning up... 10:43:31 INFO - Return code: 0 10:43:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:43: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')]} 10:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43: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')]} 10:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:43: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 mozsystemmonitor==0.3 10:43:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:31 INFO - 'HOME': '/Users/cltbld', 10:43:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:31 INFO - 'LOGNAME': 'cltbld', 10:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:31 INFO - 'MOZ_NO_REMOTE': '1', 10:43:31 INFO - 'NO_EM_RESTART': '1', 10:43:31 INFO - 'PAGER': '/bin/cat', 10:43:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:31 INFO - 'PWD': '/builds/slave/test', 10:43:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:31 INFO - 'SHELL': '/bin/bash', 10:43:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:31 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:31 INFO - 'USER': 'cltbld', 10:43:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:31 INFO - 'XPC_FLAGS': '0x0', 10:43:31 INFO - 'XPC_SERVICE_NAME': '0', 10:43:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:43:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:43:34 INFO - Installing collected packages: mozsystemmonitor 10:43:34 INFO - Running setup.py install for mozsystemmonitor 10:43:34 INFO - Successfully installed mozsystemmonitor 10:43:34 INFO - Cleaning up... 10:43:34 INFO - Return code: 0 10:43:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:35 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 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:43:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:35 INFO - 'HOME': '/Users/cltbld', 10:43:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:35 INFO - 'LOGNAME': 'cltbld', 10:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:35 INFO - 'MOZ_NO_REMOTE': '1', 10:43:35 INFO - 'NO_EM_RESTART': '1', 10:43:35 INFO - 'PAGER': '/bin/cat', 10:43:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:35 INFO - 'PWD': '/builds/slave/test', 10:43:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:35 INFO - 'SHELL': '/bin/bash', 10:43:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:35 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:35 INFO - 'USER': 'cltbld', 10:43:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:35 INFO - 'XPC_FLAGS': '0x0', 10:43:35 INFO - 'XPC_SERVICE_NAME': '0', 10:43:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:35 INFO - Downloading/unpacking jsonschema==2.5.1 10:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:43:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:38 INFO - Downloading functools32-3.2.3-2.tar.gz 10:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:43:38 INFO - warning: no files found matching '*.txt' 10:43:38 INFO - no previously-included directories found matching 'build' 10:43:38 INFO - no previously-included directories found matching 'dist' 10:43:38 INFO - no previously-included directories found matching '.git*' 10:43:38 INFO - Installing collected packages: jsonschema, functools32 10:43:38 INFO - Running setup.py install for functools32 10:43:39 INFO - warning: no files found matching '*.txt' 10:43:39 INFO - no previously-included directories found matching 'build' 10:43:39 INFO - no previously-included directories found matching 'dist' 10:43:39 INFO - no previously-included directories found matching '.git*' 10:43:39 INFO - Successfully installed jsonschema functools32 10:43:39 INFO - Cleaning up... 10:43:39 INFO - Return code: 0 10:43:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:43: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')]} 10:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43: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')]} 10:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:39 INFO - 'HOME': '/Users/cltbld', 10:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:39 INFO - 'LOGNAME': 'cltbld', 10:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:39 INFO - 'MOZ_NO_REMOTE': '1', 10:43:39 INFO - 'NO_EM_RESTART': '1', 10:43:39 INFO - 'PAGER': '/bin/cat', 10:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:39 INFO - 'PWD': '/builds/slave/test', 10:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:39 INFO - 'SHELL': '/bin/bash', 10:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:39 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:39 INFO - 'USER': 'cltbld', 10:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:39 INFO - 'XPC_FLAGS': '0x0', 10:43:39 INFO - 'XPC_SERVICE_NAME': '0', 10:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:43:39 INFO - Cleaning up... 10:43:39 INFO - Return code: 0 10:43:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:43: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')]} 10:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43: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')]} 10:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43: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', '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 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:43: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 blobuploader==1.2.4 10:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:39 INFO - 'HOME': '/Users/cltbld', 10:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:39 INFO - 'LOGNAME': 'cltbld', 10:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:39 INFO - 'MOZ_NO_REMOTE': '1', 10:43:39 INFO - 'NO_EM_RESTART': '1', 10:43:39 INFO - 'PAGER': '/bin/cat', 10:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:39 INFO - 'PWD': '/builds/slave/test', 10:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:39 INFO - 'SHELL': '/bin/bash', 10:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:39 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:39 INFO - 'USER': 'cltbld', 10:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:39 INFO - 'XPC_FLAGS': '0x0', 10:43:39 INFO - 'XPC_SERVICE_NAME': '0', 10:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:39 INFO - Downloading/unpacking blobuploader==1.2.4 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43:42 INFO - Downloading blobuploader-1.2.4.tar.gz 10:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:43:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:43:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43: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 10:43:43 INFO - Downloading docopt-0.6.1.tar.gz 10:43:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:43:43 INFO - Installing collected packages: blobuploader, requests, docopt 10:43:43 INFO - Running setup.py install for blobuploader 10:43:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:43:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:43:44 INFO - Running setup.py install for requests 10:43:44 INFO - Running setup.py install for docopt 10:43:44 INFO - Successfully installed blobuploader requests docopt 10:43:44 INFO - Cleaning up... 10:43:44 INFO - Return code: 0 10:43:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:44 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 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:43:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:44 INFO - 'HOME': '/Users/cltbld', 10:43:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:44 INFO - 'LOGNAME': 'cltbld', 10:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:44 INFO - 'MOZ_NO_REMOTE': '1', 10:43:44 INFO - 'NO_EM_RESTART': '1', 10:43:44 INFO - 'PAGER': '/bin/cat', 10:43:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:44 INFO - 'PWD': '/builds/slave/test', 10:43:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:44 INFO - 'SHELL': '/bin/bash', 10:43:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:44 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:44 INFO - 'USER': 'cltbld', 10:43:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:44 INFO - 'XPC_FLAGS': '0x0', 10:43:44 INFO - 'XPC_SERVICE_NAME': '0', 10:43:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-f5Aw7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ATo_Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-79oScT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-lR3pFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Fy_njm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-dQ4mCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-LNWA71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-W77a8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:43:45 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Qepvn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-2SOuaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-S3K2y5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-m4fBCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Z7S3t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Abo_wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-hTeAfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-yDtupT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:43:46 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-PAlADr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:43:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:43:46 INFO - Running setup.py install for manifestparser 10:43:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:43:46 INFO - Running setup.py install for mozcrash 10:43:47 INFO - Running setup.py install for mozdebug 10:43:47 INFO - Running setup.py install for mozdevice 10:43:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Running setup.py install for mozfile 10:43:47 INFO - Running setup.py install for mozhttpd 10:43:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Running setup.py install for mozinfo 10:43:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Running setup.py install for mozInstall 10:43:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:43:47 INFO - Running setup.py install for mozleak 10:43:48 INFO - Running setup.py install for mozlog 10:43:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Running setup.py install for moznetwork 10:43:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Running setup.py install for mozprocess 10:43:48 INFO - Running setup.py install for mozprofile 10:43:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Running setup.py install for mozrunner 10:43:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:43:48 INFO - Running setup.py install for mozscreenshot 10:43:49 INFO - Running setup.py install for moztest 10:43:49 INFO - Running setup.py install for mozversion 10:43:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:43:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:43:49 INFO - Cleaning up... 10:43:49 INFO - Return code: 0 10:43:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:49 INFO - 'HOME': '/Users/cltbld', 10:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:49 INFO - 'LOGNAME': 'cltbld', 10:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:49 INFO - 'MOZ_NO_REMOTE': '1', 10:43:49 INFO - 'NO_EM_RESTART': '1', 10:43:49 INFO - 'PAGER': '/bin/cat', 10:43:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:49 INFO - 'PWD': '/builds/slave/test', 10:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:49 INFO - 'SHELL': '/bin/bash', 10:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:49 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:49 INFO - 'USER': 'cltbld', 10:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:49 INFO - 'XPC_FLAGS': '0x0', 10:43:49 INFO - 'XPC_SERVICE_NAME': '0', 10:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:43:49 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-2mOqoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:43:49 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-tuiLyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:43:49 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Axv_PY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ClO_hT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zWj78G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-gZGMXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-tHQPGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-CZj_do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-QQPrbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-LW4dk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-oe81s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-YvKAK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:43:50 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-BRLsb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:43:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0ik2iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:43:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-oKoR6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:43:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-xYXFxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:43:51 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-u5gxbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:43:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:43:54 INFO - Downloading blessings-1.6.tar.gz 10:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:43:54 INFO - Installing collected packages: blessings 10:43:54 INFO - Running setup.py install for blessings 10:43:54 INFO - Successfully installed blessings 10:43:54 INFO - Cleaning up... 10:43:54 INFO - Return code: 0 10:43:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:43:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:43:54 INFO - Reading from file tmpfile_stdout 10:43:54 INFO - Current package versions: 10:43:54 INFO - blessings == 1.6 10:43:54 INFO - blobuploader == 1.2.4 10:43:54 INFO - docopt == 0.6.1 10:43:54 INFO - functools32 == 3.2.3-2 10:43:54 INFO - jsonschema == 2.5.1 10:43:54 INFO - manifestparser == 1.1 10:43:54 INFO - mozInstall == 1.12 10:43:54 INFO - mozcrash == 1.0 10:43:54 INFO - mozdebug == 0.1 10:43:54 INFO - mozdevice == 0.49 10:43:54 INFO - mozfile == 1.2 10:43:54 INFO - mozhttpd == 0.7 10:43:54 INFO - mozinfo == 0.9 10:43:54 INFO - mozleak == 0.1 10:43:54 INFO - mozlog == 3.4 10:43:54 INFO - moznetwork == 0.27 10:43:54 INFO - mozprocess == 0.25 10:43:54 INFO - mozprofile == 0.28 10:43:54 INFO - mozrunner == 6.13 10:43:54 INFO - mozscreenshot == 0.1 10:43:54 INFO - mozsystemmonitor == 0.3 10:43:54 INFO - moztest == 0.8 10:43:54 INFO - mozversion == 1.4 10:43:54 INFO - psutil == 3.1.1 10:43:54 INFO - requests == 1.2.3 10:43:54 INFO - wsgiref == 0.1.2 10:43:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:54 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 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:43:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:54 INFO - 'HOME': '/Users/cltbld', 10:43:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:54 INFO - 'LOGNAME': 'cltbld', 10:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:54 INFO - 'MOZ_NO_REMOTE': '1', 10:43:54 INFO - 'NO_EM_RESTART': '1', 10:43:54 INFO - 'PAGER': '/bin/cat', 10:43:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:54 INFO - 'PWD': '/builds/slave/test', 10:43:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:54 INFO - 'SHELL': '/bin/bash', 10:43:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:54 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:54 INFO - 'USER': 'cltbld', 10:43:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:54 INFO - 'XPC_FLAGS': '0x0', 10:43:54 INFO - 'XPC_SERVICE_NAME': '0', 10:43:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:43:55 INFO - Cleaning up... 10:43:55 INFO - Return code: 0 10:43:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:43:55 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 0x100bcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd072c82f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, '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.XCvCzwEBtK/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.NZiiTQuXan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:43:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:43:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:43:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:43:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:43:55 INFO - 'HOME': '/Users/cltbld', 10:43:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:43:55 INFO - 'LOGNAME': 'cltbld', 10:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:43:55 INFO - 'MOZ_NO_REMOTE': '1', 10:43:55 INFO - 'NO_EM_RESTART': '1', 10:43:55 INFO - 'PAGER': '/bin/cat', 10:43:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:43:55 INFO - 'PWD': '/builds/slave/test', 10:43:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:43:55 INFO - 'SHELL': '/bin/bash', 10:43:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:43:55 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:43:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:43:55 INFO - 'USER': 'cltbld', 10:43:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:43:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:43:55 INFO - 'XPC_FLAGS': '0x0', 10:43:55 INFO - 'XPC_SERVICE_NAME': '0', 10:43:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:43:55 INFO - Cleaning up... 10:43:55 INFO - Return code: 0 10:43:55 INFO - Running post-action listener: _resource_record_post_action 10:43:55 INFO - Running post-action listener: _start_resource_monitoring 10:43:55 INFO - Starting resource monitoring. 10:43:55 INFO - [mozharness: 2017-04-28 17:43:55.354885Z] Finished create-virtualenv step (success) 10:43:55 INFO - [mozharness: 2017-04-28 17:43:55.355688Z] Running install step. 10:43:55 INFO - Running pre-action listener: _resource_record_pre_action 10:43:55 INFO - Running main action method: install 10:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:43:55 INFO - Reading from file tmpfile_stdout 10:43:55 INFO - Detecting whether we're running mozinstall >=1.0... 10:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:43:55 INFO - Reading from file tmpfile_stdout 10:43:55 INFO - Output received: 10:43:55 INFO - Usage: mozinstall [options] installer 10:43:55 INFO - Options: 10:43:55 INFO - -h, --help show this help message and exit 10:43:55 INFO - -d DEST, --destination=DEST 10:43:55 INFO - Directory to install application into. [default: 10:43:55 INFO - "/builds/slave/test"] 10:43:55 INFO - --app=APP Application being installed. [default: firefox] 10:43:55 INFO - mkdir: /builds/slave/test/build/application 10:43:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:43:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:44:10 INFO - Reading from file tmpfile_stdout 10:44:10 INFO - Output received: 10:44:10 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:44:10 INFO - Running post-action listener: _resource_record_post_action 10:44:10 INFO - [mozharness: 2017-04-28 17:44:10.285968Z] Finished install step (success) 10:44:10 INFO - [mozharness: 2017-04-28 17:44:10.286091Z] Running run-tests step. 10:44:10 INFO - Running pre-action listener: _resource_record_pre_action 10:44:10 INFO - Running main action method: run_tests 10:44:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:44:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:44:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:44:10 INFO - 2017-04-28 10:44:10.300 screenresolution[1621:7315] starting screenresolution argv=screenresolution get 10:44:10 INFO - 2017-04-28 10:44:10.303 screenresolution[1621:7315] Display 0: 1600x1200x32@60 10:44:10 INFO - 2017-04-28 10:44:10.309 screenresolution[1622:7317] starting screenresolution argv=screenresolution list 10:44:10 INFO - Available Modes on Display 0 10:44:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:44:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:44:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:44:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:44:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:44:10 INFO - Intel Iris: 10:44:10 INFO - Chipset Model: Intel Iris 10:44:10 INFO - Type: GPU 10:44:10 INFO - Bus: Built-In 10:44:10 INFO - VRAM (Dynamic, Max): 1536 MB 10:44:10 INFO - Vendor: Intel (0x8086) 10:44:10 INFO - Device ID: 0x0a2e 10:44:10 INFO - Revision ID: 0x0009 10:44:10 INFO - Displays: 10:44:10 INFO - Display: 10:44:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:44:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:44:10 INFO - Display Serial Number: 1600x1200 60 10:44:10 INFO - Main Display: Yes 10:44:10 INFO - Mirror: Off 10:44:10 INFO - Online: Yes 10:44:10 INFO - Rotation: Supported 10:44:10 INFO - Return code: 0 10:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:44:10 INFO - Python 2.7.3 10:44:10 INFO - Return code: 0 10:44:10 INFO - grabbing minidump binary from tooltool 10:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:10 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 0x1009abd30>, '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 0x1008d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:44:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:44:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:44:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:44:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:44:10 INFO - Return code: 0 10:44:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:44:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:44:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:44:10 INFO - ENV: RUST_BACKTRACE is now 1 10:44:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:44:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCvCzwEBtK/Render', 10:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:10 INFO - 'HOME': '/Users/cltbld', 10:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:10 INFO - 'LOGNAME': 'cltbld', 10:44:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:44:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:10 INFO - 'MOZ_NO_REMOTE': '1', 10:44:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:44:10 INFO - 'NO_EM_RESTART': '1', 10:44:10 INFO - 'PAGER': '/bin/cat', 10:44:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:10 INFO - 'PWD': '/builds/slave/test', 10:44:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:10 INFO - 'RUST_BACKTRACE': '1', 10:44:10 INFO - 'SHELL': '/bin/bash', 10:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZiiTQuXan/Listeners', 10:44:10 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 10:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:10 INFO - 'USER': 'cltbld', 10:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:10 INFO - 'XPC_FLAGS': '0x0', 10:44:10 INFO - 'XPC_SERVICE_NAME': '0', 10:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:44:10 INFO - mozversion application_buildid: 20170428091122 10:44:10 INFO - mozversion application_changeset: 016390ad9e5c1f43f8a51ff9e212bb46e28def3e 10:44:10 INFO - mozversion application_display_name: Firefox 10:44:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:44:10 INFO - mozversion application_name: Firefox 10:44:10 INFO - mozversion application_remotingname: firefox 10:44:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:44:10 INFO - mozversion application_vendor: Mozilla 10:44:10 INFO - mozversion application_version: 54.0 10:44:10 INFO - mozversion platform_buildid: 20170428091122 10:44:10 INFO - mozversion platform_changeset: 016390ad9e5c1f43f8a51ff9e212bb46e28def3e 10:44:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:44:10 INFO - mozversion platform_version: 54.0 10:44:10 INFO - using testdate: 1493401450 10:44:10 INFO - actual date: 1493401450 10:44:10 INFO - starting webserver on 'localhost:49228' 10:44:10 INFO - SUITE-START | Running 2 tests 10:44:10 INFO - TEST-START | dromaeo_css 10:44:10 INFO - operating with platform_type : mac_ 10:44:10 INFO - Initialising browser for dromaeo_css test... 10:44:10 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpBup8md/profile http://localhost:49228/getInfo.html) 10:44:13 INFO - PROCESS | 1632 | 2017-04-28 10:44:13.723 plugin-container[1635:7475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 10:44:13 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:13 INFO - PROCESS | 1632 | 2017-04-28 10:44:13.724 plugin-container[1635:7475] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 10:44:13 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:13 INFO - PROCESS | 1632 | 2017-04-28 10:44:13.724 plugin-container[1635:7475] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:44:13 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 10:44:13 INFO - PROCESS | 1632 | colorDepth:24 10:44:13 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 10:44:13 INFO - PROCESS | 1632 | __metrics 10:44:13 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:44:14 INFO - TEST-INFO | 1632: exit 0 10:44:14 INFO - Browser initialized. 10:44:14 INFO - Running cycle 1/1 for dromaeo_css test... 10:44:14 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpBup8md/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:44:16 INFO - PROCESS | 1636 | 2017-04-28 10:44:16.808 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 10:44:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:16 INFO - PROCESS | 1636 | 2017-04-28 10:44:16.809 plugin-container[1637:7603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870f, name = 'com.apple.CFPasteboardClient' 10:44:16 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:16 INFO - PROCESS | 1636 | 2017-04-28 10:44:16.809 plugin-container[1637:7603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:44:26 INFO - PROCESS | 1636 | RSS: Main: 174379008 10:44:26 INFO - PROCESS | 1636 | 10:47:30 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:47:31 INFO - PROCESS | 1636 | RSS: Main: 189960192 10:47:31 INFO - PROCESS | 1636 | 10:50:33 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:50:34 INFO - PROCESS | 1636 | RSS: Main: 203329536 10:50:34 INFO - PROCESS | 1636 | 10:53:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 10:53:37 INFO - PROCESS | 1636 | RSS: Main: 205754368 10:53:37 INFO - PROCESS | 1636 | 10:56:38 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 10:56:39 INFO - PROCESS | 1636 | RSS: Main: 208830464 10:56:39 INFO - PROCESS | 1636 | 10:59:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 10:59:42 INFO - PROCESS | 1636 | RSS: Main: 211357696 10:59:42 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:02:45 INFO - PROCESS | 1636 | RSS: Main: 209371136 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | __start_tp_report 11:02:45 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 11:02:45 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 11:02:45 INFO - PROCESS | 1636 | |i|pagename|runs| 11:02:45 INFO - PROCESS | 1636 | |0;dojo.html;2780.6663200415946;3625.2205775758207;3648.5986541480415;3663.725220608455;3690.7416480846337;6181.443670069692;6186.47414969727;6209.565330426872;6215.254169499666;6216.627002379849;6118.69406529674;6203.565750397463;6217.844553886155;6221.7822376216845;6241.375862413756;5522.116461366192;5593.74828132733;5599.076152434864;5616.185653080322;5636.859078523049;5628.240187574666;5648.858778530549;5652.152177173416;5653.067243904751;5654.519365853907;5412.5940554458575;5414.593905457087;5558.749856256478;5567.164925261202;5617.9438205618035;5592.468715472064;5631.774729010835;5635.239242702224;5660.60375773696;5694.971525142369;5472.972635136839;5569.972150139223;5581.916271255935;5603.327600687903;5632.549396048306;6005.399460054004;6011.57918945674;6047.727852246616;6085.908711369333;6211.503079753654;6538.117354157175;6571.2771595124495;6576.802695919082;6590.373914478117;6591.077249185118;6675.399214070709;6679.465642748569;6680.632565208912;6689.096971908827;6694.665266736643;5592.356878958931;6095.756169753249;6248.218972628421;6283.3088360280335;6321.1782468278825;6601.966990165019;6603.834904127435;6673;6775.4240889524945;6788.728450862009;6517.4460170886005;6542.836429089216;6551.246606640202;6555.6066636002;6567.1462709848365;6646.700898459484;6662.900056499157;6698.095757072733;6711.798646040529;6737.764178253738;6289.371062893674;6332.461740752087;6340.953742632506;6594.604323740591;6645.102911106938;6437.259715132726;6491.480681545465;6512.088307636935;6515.51133664977;6533.934660653427;4046.2716710992304;4497.8425755098415;4870.464248932614;4876.000419913909;5334.893302133935;4630.814767409333;4693.5071817458675;4708.788104535304;4731.408573928259;4731.574158325767;6243.625382477066;6284.874302513924;6291.182146320949;6323.177986861679;6371.076193951851;5572.693501857437;6715.731370745116;6728.259891411941;6746.359095885981;6760.7295708172105;5289.68261904287;6358.1734374531015;6390.680465976682;6399.456046236122;6410.775622853178;5614.6631202128;5682.176084467729;5714.913946468861;5789.363170051291;5819.447152520504;6885;6927.68825402848;6953.33943275388;6954.965225173842;6962.990366396945;6648.401643852077;6665.400113989765;6669.833254168685;6670.366315200048;6693.665316734241;6592.24189218246;6694.230163531256;6804.149481314836;6860.622665753432;6971.51199416036;6476.352364763486;6493.155889734493;6537.05212744159;6538.574992625464;6546.116274302908;5550.611457197957;6375.776847810304;6386.393292637192;6483.935160648333;6639.568428052355;6246.219222597176;6395.808125756234;6405.615663060045;6407.391297826699;6412.711427985845;5477.643953143033;6261.373862613702;6292.748290068346;6496.70764815575;6523.282438931618;6143.051335359142;6248.750049998131;6302.401271879165;6318.557700961072;6335.556511044183;6495.935040649722;6508.088867558451;6515.087887695821;6522.05430212607;6524.4454221389815;6238.812835614939;6300.527460440577;6342.682865856781;6370.4903607712195;6373.458256048288 11:02:45 INFO - PROCESS | 1636 | |1;ext.html;788.9329407000405;830.551502188818;857.502648463891;986.5166068626376;988.6589126751269;31907.68092319076;42552;42658.72023839282;42795.57204427955;42811.35782963254;41362.58637413625;41376;41469;41557;41572;8343.040550336713;8476.87284690727;11326.32042077478;11577.247478913843;11751.647450576455;11374;11380.089592832554;11423.543058277659;11480.425978701074;11511.942440287787;11438;11464.71338216543;11475.827862582068;11489.138314626394;11500.079993600491;11343.205975581712;11347.489362978686;11349.943250283737;11401.885981140165;11443.942780286128;11127.165462590337;11178.776424471547;11183.83224251637;11203.159763017806;11207.383593902332;36594.81702591496;36609.901702948955;36620.53517859282;36769.448458273146;36788.44817327742;3939.4090886367276;3943.743656662308;3974.5627980922077;3990.4213888985932;4039.434479172918;21222.681659775117;26367;26486.73513264862;27719.86140069307;28171;3155.952660710091;3207.4547326954475;3242.254281515241;3257.1531401835455;3333.933321333584;323688;348236.7764483535;348960;350109;353371;38628;46398;46465.5353446468;46503;46574.76712616415;48427.27359089616;48685.756571216916;48694;48702;48822.511774881794;14893.255337233095;14944.178070206242;15046.924765376103;15060.849391506164;15086.773698394532;12706.174098683556;12829.871701283055;12831.743365132645;12857.485700571873;13062.085654004128;11494.94252528732;12199.634010979686;12248.265104093605;12326.260424374566;12416.379181040911;5379.408265090837;5389.730513474389;5395.325584301962;5762.827115186551;5807.216025836543;1136.545381847251;1172.4841069929205;1189.7501524679915;1207.9490842966675;1230.698478872682;23064.769352306597;27429.86285068562;27725.58411623827;27846.74689638971;27894.442111157663;1285.3252042677668;1299.389287035092;1306.1901620995043;1341.7182391697659;1343.7782765843722;14044.438222471192;14291.499797507246;14341.426342946374;14564.053336533092;14632.414703411958;366104;448619.75690121995;449994;451904;454512;54914.725426373414;61281.69359153265;61523;61544;61733.45666358377;1459.6423876150411;1482.7479328514178;1499.3253036133783;1499.550134959508;1501.1743541052597;28207.43585128327;28530.85734571314;28926.409047502588;29371.265718357212;29605.85197074001;44398.11203775971;44432.77783611061;44484.77757611191;44563.77718111389;44571;408708;410224.8977510306;410719;410727;410945;30328.54507182438;30710.385792284476;30900.918467854415;30998.690013099582;31205.90779322713;39369.40945885871;40056.39915401213;40616.79691601523;40925;41104.58895411008;28375.723092461303;28584.428311434072;29337.266568335108;29521.704782952755;29770.70229297679;28505.42989140164;28607.570886437137;29361.265968350963;29407.558886617146;29724.554131688474;33638.32723345471;33722.32555348928;34333.79831705878;34354.48468272928;34391.4841277376;28055.456949867137;28510;28656.856715716287;28686.852525899565;29479.705202947694;18435.80167289122;19019.90490047541;19500;23819.285421436693;23833.046678133374 11:02:45 INFO - PROCESS | 1636 | |2;jquery.html;438.1652951128102;443.03418547566304;443.8934655682637;452.19283578811815;460.64990607138566;18413.907930460347;20832.270870519536;22266.777332226695;22591.322260332174;22934.65598016031;20163.58854880159;21140.682889756667;22721.886390568023;22881.771182288172;23701.40746481339;6884.173899132111;7035.226125126232;7074.575525468463;7163.14042314923;7172.784816455514;6360.395762402564;6819.386255237027;7148.3209095136135;7165.390941769956;7172.462065345094;8022.719204827832;8093.988251468566;8108.878366824473;8112.107668156498;8127.187281271885;7818.6872525098925;7985.321247693924;7994.680212791482;8068.515889046617;8086.5148091114725;7686.846263074764;7755.728549500796;7808.804779880492;7815.531068135873;7869;26605.733942660518;26918.86540567304;27179.912803487838;27604.585931211048;27724.861375693195;2914.6793852676187;2993.2965753048015;3076.5385192221124;3094.7524198064325;3097.396007778484;13826;15864.46091274304;15864.60338491547;16632.25154868034;16656.250468728816;2100.5798840232014;2121.5733015494575;2142.2930432957087;2157.8057974782346;2164.1235299703644;156551;159702;164344.17827910904;168286;168349.3165068358;22503.88748056249;22558.548829023326;22680.319590411986;22922.96846641905;22969.540609187723;33556.664433355356;33720.32559348848;34176.48735268973;34254.48618270728;34267.800626977936;10873;11046.502907369188;11192.216544841946;11197.832032519518;11394.601188958346;7705.653245603849;9331;9344.299177561574;9358.251339892793;9507.477088760184;7541.909141873374;9933.751656208653;10028.448435336126;10251.436171010666;10304.381737095799;4325.52419233884;4377.5403582624;4399.846005389796;4417.050334178167;4421.38100665907;798.594473726246;927.1331305229631;939.2110627073291;953.0612346838359;982.5676702250987;18307;20992.992396004418;21665.891670541863;21751.021204045857;22035.449113772283;883.293365307764;922.2760133295465;967.8064387122604;987.2940847294219;989.1443901025561;11021.614243501439;11312.490937907649;11339.716507087389;11381.146414018816;11564.826527602092;689092;706816;710128;717039.4148029226;720979;129637.70362296444;161682;163406.1829690844;164408;165921;1244.3840299052026;1256.6795467155816;1273.3187744556617;1275.942582583786;1277.2911034375825;18883.811161888207;19095.618087638446;19209.519762005504;19231.03844807726;19472.61054778868;64245;64557.67721161364;64612.676936616896;64947;66651;90520;98424;99400;100063.99936000547;101196.49401752945;7718.459707820399;10522.894771052192;11792.115591330856;12139.332336721565;12166.45250963691;12770.553030643883;12897.29064901402;13010.544630937871;13312.800307995582;13525.323733813073;11344.149188810707;11396.715082123013;11744.236624619372;11748.060155187735;11892.524299027942;10362.948185259025;10528.210384221367;10615.628453004108;10695.197860160364;10843.67468975932;11582.536698531965;11734;12021.579244726392;12070;12070.3361315125;10679.305845120043;11833.526658933546;11860.644180674593;12054.457549410474;12288.938555307166;11302.604408845651;11389.658310250707;11446.255993360404;11793.29240245588;11826.994705450134 11:02:45 INFO - PROCESS | 1636 | |3;mootools.html;1617.417729617338;1637.1895911523798;1668.2242757117945;1678.6978343898102;1684.3094331324155;10941.234113612041;11546.076313894868;11613.129015323853;11702.707432314197;11941.223820451665;9640.517974101285;11436.942815285933;11630.592929247458;11632;11710.473028713706;2078.760942491614;2507.912223072193;2511.1713134665515;2513.6606558114695;2518.9244322670347;2429.6841410616685;2494.5135698538793;2494.663220465232;2508.0093363121555;2512.6231065340253;2300.424893776556;2307.653851922208;2372.478054827935;2494.8627825469594;2508.4606809535944;2027.929022484206;2465.543874383227;2492.015653816741;2496.8876400561926;2499.237732491589;2449.730529641738;2465.827392082555;2480.355107672;2483.6026235802183;2497.600383938561;11151.553937842476;11350.375729334883;11352.375619340934;11362;11365.261258018203;5999.76000959961;6100.572959892809;6154.72303746328;6157.168782214389;6168.7224074916285;3653.7442379033478;3715;3783.9621603783885;3789.0716774390175;3859.5754467008605;1275.483429211171;1838.1360760442572;1838.3473866777235;1859.1355019915668;1935.525796179945;12996.675083122904;13503.392347344394;13817.723645527034;15417.922910385489;15775.842241577551;3965.5637879833193;4768.0702263059065;4840.273958906122;5001.5498605124985;5039.966806804597;12131.393430328448;12141.69645758863;12146.149769516225;12178.208416452902;12289.893909548184;6068.787592434243;6127.662978536134;6127.785527506605;6129.693515324216;6139.723712432951;5365.785368585291;5476.151196564539;5485.122380419193;5497.8075767347955;5627.099664053731;4061.1268577256033;4105.466289382421;4109.938350924738;4178.603032711888;4206.158768246363;932.654917680449;1088.6679562733443;1096.6107032003524;1101.8347247912716;1109.328856042099;983.2478154211891;990.4107056301489;1001.8196724589498;1011.7066050845319;1017.9592816287413;5445.809396671177;6681.5656982296;6772.661366931634;6792.660366981631;6940.09778728762;998.6354980432089;1029.2949329709024;1036.7459972306833;1044.8497779195732;1086.266769930294;3778.6599206071587;4254.404598275647;4273.67947403946;4473.1277400907165;4512.751798651106;13428.19430834153;13642.181469111885;13961.092528985584;13962.301884905713;13980.72038559223;13587.320633968262;13953.930230348784;13966.162030278216;13969.092009019381;14261.928690356483;8967.148120928632;10208.43853588045;10481.109105725945;10496;10521.3687178768;6705.865882682417;6948.235694073547;7290.744823931137;7320.560766354036;7324.523905946096;11913.22564033335;12148;12239.632811015685;12303.261804291413;12430.192037517532;12592.937035314764;12716.537598176328;12726.872731272939;12763.680907977376;12926.870731292567;8325.375596830141;8426.536540490331;8497.702580409903;8499.447535910145;8517.403781735467;10295.897041029493;10398.844017339898;10524.210684198562;10526.000029997138;10537.15702743794;8857.335699822668;8952.28381729447;9024.36829421934;9029.864552031857;9167.17495425415;8707.390482666153;8807.119288071142;8815.735527934172;8858.77853053653;8859.158379953895;8018.959905200437;8158.959205203936;8179.182081791773;8190.099089100075;8198.959005204935;5928.288605367384;5967.46292833647;5989.760409583568;6065.968785306597;6110.663913484801;6029.457348838626;6082.178905847831;6217.4715149212825;6223.128761973418;6329.398707122816 11:02:45 INFO - PROCESS | 1636 | |4;prototype.html;399.7901101921489;401.24966312994684;403.17952965714767;406.7254603142879;407.70848843077215;4705.694129881555;5236.738163091844;5396.001739678162;5415;5433.5768465866495;4313.633341166004;4766.380370551832;5273.314469119019;5279.234510995903;5293.338332708411;3632.8365223565;4536.412255710508;4538.865283679081;4559.886002849922;4578.58792708657;4027.597240275978;4547.954520454803;4587.42657167854;4596.494385617596;4597.747123908183;3533.3639944809925;3814.332491813935;3952.367621180612;4115.71190016699;4276.524599013335;3677.0991107178643;3926.7447615904875;4336.980809509762;4359.651227901792;4433.7118087324225;3422.640622734602;3728.45937339087;3854.69162467002;3860.9227815443533;4031.629246056355;4430.579094986004;4757.09615173116;4811.711297322172;5251.238570407269;5306.814261500829;4912.238603016538;5076.923846142311;5116.565091967187;5423.718650216601;5431.429699881495;4979.979104283613;5266.894662106774;5358.955003774266;5623.3289187635955;5880.235569375996;4457.955420445786;4484.8430304939175;4754.477007529169;4999.275105109738;5041.647084704072;5645.407232240637;5811.854703632401;5885.499197704558;5964.910526342108;5990.311114221877;4261.4673165854265;4454.710443821141;5066.316047333563;5161;5181.53366197044;5307.734613269398;5328.7121196987155;5332.893342133136;5335;5360.276362691065;5112.284280200774;5418.51233388989;5490.423505531941;5541.010472459822;5564.27664403633;4931.605471562267;5729.742161602738;5753.539716822728;5763.452472015152;5859.220802582396;1653.9917300413422;1818.4362847517311;1822.88151270167;1833.6247814139394;1840.7514985477058;1391.1428243295313;1428.286056731905;1483.8961272710806;1492.5522343296968;1510.8262549806693;4591.7602247393015;5151.9484805152215;5162.535371816554;5316.468353164652;5318.596098496965;5189.506996835295;5260.947810437851;5443.148615238634;5887.852803679943;5959.404059594092;4390.253656878338;5124.948750512522;5274.551663108678;5449.945500545023;5461.672299662033;2685.4092099738027;3066.635347270443;3077.892273770452;3084.0131158029662;3104.9534256986162;5273.709945952933;5631.551074651062;5808.838232353546;6242.070033468223;6262.937370626236;6049.576529642883;6122.53059265773;6144.695739097839;6150.831342045087;6163.784267550704;5675.4040825714355;6205.6586887720705;6214.409631084949;6220.844478887973;6278.515423939666;5491.368492623399;5892.731298450213;5897.292324921037;5903.734331954986;5954.404559544011;5622.803201887914;5868.911966320594;5878.589778190922;5891.23413956183;5893.911591326219;4762.119007983396;5943.195041677169;6041.33863187627;6095.542834287357;6114.38856114385;4239.982404223026;4503.909921801611;4635.652326075625;4695.548771652764;4726.243800991825;4587.779243096293;5777.277840269966;5891.674373265981;5969.164316995537;5980.37206093354;5207.609429292743;5474.562035037108;5484.91772623403;5681.375048744552;5720.856978575569;5702.002149623716;5709.8287051388525;5711.628744131618;5723.570732195185;5727.942720572741;5100.617453690914;5320.52115309624;5505.807296744595;5526.392096869422;5528.944710552843;4938.358013458227;5420.970015697163;5714.085746280575;5781.335146458211;5793.478586927028;5294.352823588206;5349.679019258857;5396.64921780083;5581.74882130297;5640.266765320482 11:02:45 INFO - PROCESS | 1636 | |5;yui.html;770.7803276066322;777.3314949143739;811.7037281392292;812.3947658994047;918.4397517514317;15870.523884283475;17807.198676059576;18019.189136488858;18033.458996230118;18058.91646501211;15898.920505397486;16523.83476165238;16550.172491375444;16620.33518659255;16643.084630345325;993.9652112176075;1013.8479228115768;1013.878334599849;1016.3343010328247;1018.4195008844962;954.0840792838849;988.9011098890089;1000.7298029532022;1000.7348052766023;1002.2683441087974;1053.2521909444265;1067.7971185474773;1068.0707784227732;1068.2628985999675;1069.03786592067;1041.5417216424748;1061.0556604621893;1062.229883334584;1063.4470075560703;1063.8563793887843;778.2567647896245;790.4387884601939;791.2404092071619;792.6472719639762;793.9206079392072;17465.7380139298;17517.737233941374;17610.471685849316;18074.728879066824;18166.091695415307;14738.778918316233;14777.778333325008;14825.629359266104;14828.925855370762;14897.255137243203;12106.576269830603;12255.816162757566;12257.93871030648;12341.876581234252;12477.688057798538;3783;3802.2775672622115;3885.2812229737306;3919.7648141111627;4029.3351596986463;114184.71630567426;117026.41486792597;117997;118471.99999999914;120381;9460.763480912894;9721.881983571839;11207.775844483065;11343.205975581795;11354.772904541864;22830.086796527954;22929.853507324566;23221.88389058044;23258;23259.53480930406;8132.390070744717;8191.754247372588;8206.384521160937;8210.507369557845;8259.884915536446;4404.273294906368;4496.595306422372;4557.544245575482;4565.315202719618;4627.375304333939;3690.2988432197794;3727.38498147808;3797.7911214882893;3802.3535998880257;3822.50307460034;671.3615491119946;674.5379415100672;683.7504310926575;688.1948120698794;691.1360799001249;690.6822861483773;708.450950513346;721.1742554774753;734.1410549656914;772.8377040821377;9199.724008279762;10003.099721025143;10634.14926805869;10929.672109836718;10952.890471095187;694.9096617439801;705.386313906894;717.9030830837875;740.5519660605364;747.5776186454635;5185.092608793446;5296.682199068068;5305.151175811851;5310.522053015247;5317.41508434072;108457;108938.45530772454;108962;109931.45034274778;112073;90604;92175.53912230396;92548.53725731328;93963.0603693968;94502.0549794507;76193.61903190338;81545.77681334785;82157;82364;83429;9646.807063858823;9694.56374463165;9850.965648606792;10154.95585126228;11166.553337866719;35348.646513534535;36353.091172720895;46884.53115468802;48196.759016206095;49762;59559.1066134017;59579;61150.69424652848;61846.0723089163;62574.68712656407;11202.719932001764;11585.710357241136;11726.824097638371;12120.333381664093;13213.339333033118;13069.673258168621;13357.398917048562;15013.624659383166;15291.847081529042;15671.608209794846;14006.43974241019;14122.364493598017;14458.589500916325;15040.17279049619;15345.923270383577;14182.574522764333;14367.28163591837;14711.55865324042;14866.256687165382;14876.330565124379;12196.756064878475;13611.455541778218;13847;14120.858791411954;14198.645033874236;9980.600775969171;10425.843612345669;10470.581176752845;10481.109105726098;10502.264841461025;8779.063705557353;9249.630014799333;11420.455109804336;11629.302241865515;11632.88367116318 11:02:45 INFO - PROCESS | 1636 | __end_tp_report 11:02:45 INFO - PROCESS | 1636 | __start_cc_report 11:02:45 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,392 11:02:45 INFO - PROCESS | 1636 | __end_cc_report 11:02:45 INFO - PROCESS | 1636 | __startTimestamp1493402565681__endTimestamp 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | ------- Summary: start ------- 11:02:45 INFO - PROCESS | 1636 | Number of tests: 6 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:6096.12 Median:6323.18 stddev:726.69 (11.5%) stddev-sans-first:680.79 11:02:45 INFO - PROCESS | 1636 | Values: 2780.7 3625.2 3648.6 3663.7 3690.7 6181.4 6186.5 6209.6 6215.3 6216.6 6118.7 6203.6 6217.8 6221.8 6241.4 5522.1 5593.7 5599.1 5616.2 5636.9 5628.2 5648.9 5652.2 5653.1 5654.5 5412.6 5414.6 5558.7 5567.2 5617.9 5592.5 5631.8 5635.2 5660.6 5695.0 5473.0 5570.0 5581.9 5603.3 5632.5 6005.4 6011.6 6047.7 6085.9 6211.5 6538.1 6571.3 6576.8 6590.4 6591.1 6675.4 6679.5 6680.6 6689.1 6694.7 5592.4 6095.8 6248.2 6283.3 6321.2 6602.0 6603.8 6673.0 6775.4 6788.7 6517.4 6542.8 6551.2 6555.6 6567.1 6646.7 6662.9 6698.1 6711.8 6737.8 6289.4 6332.5 6341.0 6594.6 6645.1 6437.3 6491.5 6512.1 6515.5 6533.9 4046.3 4497.8 4870.5 4876.0 5334.9 4630.8 4693.5 4708.8 4731.4 4731.6 6243.6 6284.9 6291.2 6323.2 6371.1 5572.7 6715.7 6728.3 6746.4 6760.7 5289.7 6358.2 6390.7 6399.5 6410.8 5614.7 5682.2 5714.9 5789.4 5819.4 6885.0 6927.7 6953.3 6955.0 6963.0 6648.4 6665.4 6669.8 6670.4 6693.7 6592.2 6694.2 6804.1 6860.6 6971.5 6476.4 6493.2 6537.1 6538.6 6546.1 5550.6 6375.8 6386.4 6483.9 6639.6 6246.2 6395.8 6405.6 6407.4 6412.7 5477.6 6261.4 6292.7 6496.7 6523.3 6143.1 6248.8 6302.4 6318.6 6335.6 6495.9 6508.1 6515.1 6522.1 6524.4 6238.8 6300.5 6342.7 6370.5 6373.5 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:53583.01 Median:29665.20 stddev:105550.15 (355.8%) stddev-sans-first:105771.82 11:02:45 INFO - PROCESS | 1636 | Values: 788.9 830.6 857.5 986.5 988.7 31907.7 42552.0 42658.7 42795.6 42811.4 41362.6 41376.0 41469.0 41557.0 41572.0 8343.0 8476.9 11326.3 11577.2 11751.6 11374.0 11380.1 11423.5 11480.4 11511.9 11438.0 11464.7 11475.8 11489.1 11500.1 11343.2 11347.5 11349.9 11401.9 11443.9 11127.2 11178.8 11183.8 11203.2 11207.4 36594.8 36609.9 36620.5 36769.4 36788.4 3939.4 3943.7 3974.6 3990.4 4039.4 21222.7 26367.0 26486.7 27719.9 28171.0 3156.0 3207.5 3242.3 3257.2 3333.9 323688.0 348236.8 348960.0 350109.0 353371.0 38628.0 46398.0 46465.5 46503.0 46574.8 48427.3 48685.8 48694.0 48702.0 48822.5 14893.3 14944.2 15046.9 15060.8 15086.8 12706.2 12829.9 12831.7 12857.5 13062.1 11494.9 12199.6 12248.3 12326.3 12416.4 5379.4 5389.7 5395.3 5762.8 5807.2 1136.5 1172.5 1189.8 1207.9 1230.7 23064.8 27429.9 27725.6 27846.7 27894.4 1285.3 1299.4 1306.2 1341.7 1343.8 14044.4 14291.5 14341.4 14564.1 14632.4 366104.0 448619.8 449994.0 451904.0 454512.0 54914.7 61281.7 61523.0 61544.0 61733.5 1459.6 1482.7 1499.3 1499.6 1501.2 28207.4 28530.9 28926.4 29371.3 29605.9 44398.1 44432.8 44484.8 44563.8 44571.0 408708.0 410224.9 410719.0 410727.0 410945.0 30328.5 30710.4 30900.9 30998.7 31205.9 39369.4 40056.4 40616.8 40925.0 41104.6 28375.7 28584.4 29337.3 29521.7 29770.7 28505.4 28607.6 29361.3 29407.6 29724.6 33638.3 33722.3 34333.8 34354.5 34391.5 28055.5 28510.0 28656.9 28686.9 29479.7 18435.8 19019.9 19500.0 23819.3 23833.0 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:43030.13 Median:22574.94 stddev:119052.93 (527.4%) stddev-sans-first:119343.95 11:02:45 INFO - PROCESS | 1636 | Values: 438.2 443.0 443.9 452.2 460.6 18413.9 20832.3 22266.8 22591.3 22934.7 20163.6 21140.7 22721.9 22881.8 23701.4 6884.2 7035.2 7074.6 7163.1 7172.8 6360.4 6819.4 7148.3 7165.4 7172.5 8022.7 8094.0 8108.9 8112.1 8127.2 7818.7 7985.3 7994.7 8068.5 8086.5 7686.8 7755.7 7808.8 7815.5 7869.0 26605.7 26918.9 27179.9 27604.6 27724.9 2914.7 2993.3 3076.5 3094.8 3097.4 13826.0 15864.5 15864.6 16632.3 16656.3 2100.6 2121.6 2142.3 2157.8 2164.1 156551.0 159702.0 164344.2 168286.0 168349.3 22503.9 22558.5 22680.3 22923.0 22969.5 33556.7 33720.3 34176.5 34254.5 34267.8 10873.0 11046.5 11192.2 11197.8 11394.6 7705.7 9331.0 9344.3 9358.3 9507.5 7541.9 9933.8 10028.4 10251.4 10304.4 4325.5 4377.5 4399.8 4417.1 4421.4 798.6 927.1 939.2 953.1 982.6 18307.0 20993.0 21665.9 21751.0 22035.4 883.3 922.3 967.8 987.3 989.1 11021.6 11312.5 11339.7 11381.1 11564.8 689092.0 706816.0 710128.0 717039.4 720979.0 129637.7 161682.0 163406.2 164408.0 165921.0 1244.4 1256.7 1273.3 1275.9 1277.3 18883.8 19095.6 19209.5 19231.0 19472.6 64245.0 64557.7 64612.7 64947.0 66651.0 90520.0 98424.0 99400.0 100064.0 101196.5 7718.5 10522.9 11792.1 12139.3 12166.5 12770.6 12897.3 13010.5 13312.8 13525.3 11344.1 11396.7 11744.2 11748.1 11892.5 10362.9 10528.2 10615.6 10695.2 10843.7 11582.5 11734.0 12021.6 12070.0 12070.3 10679.3 11833.5 11860.6 12054.5 12288.9 11302.6 11389.7 11446.3 11793.3 11827.0 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:6871.87 Median:2498.42 stddev:4198.33 (168.0%) stddev-sans-first:4191.54 11:02:45 INFO - PROCESS | 1636 | Values: 1617.4 1637.2 1668.2 1678.7 1684.3 10941.2 11546.1 11613.1 11702.7 11941.2 9640.5 11436.9 11630.6 11632.0 11710.5 2078.8 2507.9 2511.2 2513.7 2518.9 2429.7 2494.5 2494.7 2508.0 2512.6 2300.4 2307.7 2372.5 2494.9 2508.5 2027.9 2465.5 2492.0 2496.9 2499.2 2449.7 2465.8 2480.4 2483.6 2497.6 11151.6 11350.4 11352.4 11362.0 11365.3 5999.8 6100.6 6154.7 6157.2 6168.7 3653.7 3715.0 3784.0 3789.1 3859.6 1275.5 1838.1 1838.3 1859.1 1935.5 12996.7 13503.4 13817.7 15417.9 15775.8 3965.6 4768.1 4840.3 5001.5 5040.0 12131.4 12141.7 12146.1 12178.2 12289.9 6068.8 6127.7 6127.8 6129.7 6139.7 5365.8 5476.2 5485.1 5497.8 5627.1 4061.1 4105.5 4109.9 4178.6 4206.2 932.7 1088.7 1096.6 1101.8 1109.3 983.2 990.4 1001.8 1011.7 1018.0 5445.8 6681.6 6772.7 6792.7 6940.1 998.6 1029.3 1036.7 1044.8 1086.3 3778.7 4254.4 4273.7 4473.1 4512.8 13428.2 13642.2 13961.1 13962.3 13980.7 13587.3 13953.9 13966.2 13969.1 14261.9 8967.1 10208.4 10481.1 10496.0 10521.4 6705.9 6948.2 7290.7 7320.6 7324.5 11913.2 12148.0 12239.6 12303.3 12430.2 12592.9 12716.5 12726.9 12763.7 12926.9 8325.4 8426.5 8497.7 8499.4 8517.4 10295.9 10398.8 10524.2 10526.0 10537.2 8857.3 8952.3 9024.4 9029.9 9167.2 8707.4 8807.1 8815.7 8858.8 8859.2 8019.0 8159.0 8179.2 8190.1 8199.0 5928.3 5967.5 5989.8 6066.0 6110.7 6029.5 6082.2 6217.5 6223.1 6329.4 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4834.99 Median:5286.29 stddev:1325.48 (25.1%) stddev-sans-first:1286.72 11:02:45 INFO - PROCESS | 1636 | Values: 399.8 401.2 403.2 406.7 407.7 4705.7 5236.7 5396.0 5415.0 5433.6 4313.6 4766.4 5273.3 5279.2 5293.3 3632.8 4536.4 4538.9 4559.9 4578.6 4027.6 4548.0 4587.4 4596.5 4597.7 3533.4 3814.3 3952.4 4115.7 4276.5 3677.1 3926.7 4337.0 4359.7 4433.7 3422.6 3728.5 3854.7 3860.9 4031.6 4430.6 4757.1 4811.7 5251.2 5306.8 4912.2 5076.9 5116.6 5423.7 5431.4 4980.0 5266.9 5359.0 5623.3 5880.2 4458.0 4484.8 4754.5 4999.3 5041.6 5645.4 5811.9 5885.5 5964.9 5990.3 4261.5 4454.7 5066.3 5161.0 5181.5 5307.7 5328.7 5332.9 5335.0 5360.3 5112.3 5418.5 5490.4 5541.0 5564.3 4931.6 5729.7 5753.5 5763.5 5859.2 1654.0 1818.4 1822.9 1833.6 1840.8 1391.1 1428.3 1483.9 1492.6 1510.8 4591.8 5151.9 5162.5 5316.5 5318.6 5189.5 5260.9 5443.1 5887.9 5959.4 4390.3 5124.9 5274.6 5449.9 5461.7 2685.4 3066.6 3077.9 3084.0 3105.0 5273.7 5631.6 5808.8 6242.1 6262.9 6049.6 6122.5 6144.7 6150.8 6163.8 5675.4 6205.7 6214.4 6220.8 6278.5 5491.4 5892.7 5897.3 5903.7 5954.4 5622.8 5868.9 5878.6 5891.2 5893.9 4762.1 5943.2 6041.3 6095.5 6114.4 4240.0 4503.9 4635.7 4695.5 4726.2 4587.8 5777.3 5891.7 5969.2 5980.4 5207.6 5474.6 5484.9 5681.4 5720.9 5702.0 5709.8 5711.6 5723.6 5727.9 5100.6 5320.5 5505.8 5526.4 5528.9 4938.4 5421.0 5714.1 5781.3 5793.5 5294.4 5349.7 5396.6 5581.7 5640.3 11:02:45 INFO - PROCESS | 1636 | 11:02:45 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:21170.86 Median:18066.82 stddev:30825.50 (170.6%) stddev-sans-first:30873.92 11:02:45 INFO - PROCESS | 1636 | Values: 770.8 777.3 811.7 812.4 918.4 15870.5 17807.2 18019.2 18033.5 18058.9 15898.9 16523.8 16550.2 16620.3 16643.1 994.0 1013.8 1013.9 1016.3 1018.4 954.1 988.9 1000.7 1000.7 1002.3 1053.3 1067.8 1068.1 1068.3 1069.0 1041.5 1061.1 1062.2 1063.4 1063.9 778.3 790.4 791.2 792.6 793.9 17465.7 17517.7 17610.5 18074.7 18166.1 14738.8 14777.8 14825.6 14828.9 14897.3 12106.6 12255.8 12257.9 12341.9 12477.7 3783.0 3802.3 3885.3 3919.8 4029.3 114184.7 117026.4 117997.0 118472.0 120381.0 9460.8 9721.9 11207.8 11343.2 11354.8 22830.1 22929.9 23221.9 23258.0 23259.5 8132.4 8191.8 8206.4 8210.5 8259.9 4404.3 4496.6 4557.5 4565.3 4627.4 3690.3 3727.4 3797.8 3802.4 3822.5 671.4 674.5 683.8 688.2 691.1 690.7 708.5 721.2 734.1 772.8 9199.7 10003.1 10634.1 10929.7 10952.9 694.9 705.4 717.9 740.6 747.6 5185.1 5296.7 5305.2 5310.5 5317.4 108457.0 108938.5 108962.0 109931.5 112073.0 90604.0 92175.5 92548.5 93963.1 94502.1 76193.6 81545.8 82157.0 82364.0 83429.0 9646.8 9694.6 9851.0 10155.0 11166.6 35348.6 36353.1 46884.5 48196.8 49762.0 59559.1 59579.0 61150.7 61846.1 62574.7 11202.7 11585.7 11726.8 12120.3 13213.3 13069.7 13357.4 15013.6 15291.8 15671.6 14006.4 14122.4 14458.6 15040.2 15345.9 14182.6 14367.3 14711.6 14866.3 14876.3 12196.8 13611.5 13847.0 14120.9 14198.6 9980.6 10425.8 10470.6 10481.1 10502.3 8779.1 9249.6 11420.5 11629.3 11632.9 11:02:45 INFO - PROCESS | 1636 | -------- Summary: end -------- 11:02:45 INFO - PROCESS | 1636 | 11:02:46 INFO - TEST-INFO | 1636: exit 0 11:02:46 INFO - TEST-OK | dromaeo_css | took 1115360ms 11:02:46 INFO - TEST-START | kraken 11:02:46 INFO - operating with platform_type : mac_ 11:02:46 INFO - Initialising browser for kraken test... 11:02:46 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpuXrjD5/profile http://localhost:49228/getInfo.html) 11:02:48 INFO - PROCESS | 1649 | 2017-04-28 11:02:48.728 plugin-container[1651:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 11:02:48 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:48 INFO - PROCESS | 1649 | 2017-04-28 11:02:48.728 plugin-container[1651:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.CFPasteboardClient' 11:02:48 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:48 INFO - PROCESS | 1649 | 2017-04-28 11:02:48.728 plugin-container[1651:10076] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:02:48 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:02:48 INFO - PROCESS | 1649 | colorDepth:24 11:02:48 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:02:48 INFO - PROCESS | 1649 | __metrics 11:02:48 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:02:49 INFO - TEST-INFO | 1649: exit 0 11:02:49 INFO - Browser initialized. 11:02:49 INFO - Running cycle 1/1 for kraken test... 11:02:49 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpuXrjD5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:02:51 INFO - PROCESS | 1652 | 2017-04-28 11:02:51.768 plugin-container[1653:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 11:02:51 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:51 INFO - PROCESS | 1652 | 2017-04-28 11:02:51.770 plugin-container[1653:10198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 11:02:51 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:02:51 INFO - PROCESS | 1652 | 2017-04-28 11:02:51.770 plugin-container[1653:10198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:03:01 INFO - PROCESS | 1652 | RSS: Main: 174551040 11:03:01 INFO - PROCESS | 1652 | 11:03:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:03:05 INFO - PROCESS | 1652 | RSS: Main: 188067840 11:03:05 INFO - PROCESS | 1652 | 11:03:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:03:08 INFO - PROCESS | 1652 | RSS: Main: 196157440 11:03:08 INFO - PROCESS | 1652 | 11:03:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:03:12 INFO - PROCESS | 1652 | RSS: Main: 199880704 11:03:12 INFO - PROCESS | 1652 | 11:03:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:03:14 INFO - PROCESS | 1652 | RSS: Main: 200601600 11:03:14 INFO - PROCESS | 1652 | 11:03:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:03:17 INFO - PROCESS | 1652 | RSS: Main: 200396800 11:03:17 INFO - PROCESS | 1652 | 11:03:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:03:21 INFO - PROCESS | 1652 | RSS: Main: 205500416 11:03:21 INFO - PROCESS | 1652 | 11:03:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:03:25 INFO - PROCESS | 1652 | RSS: Main: 205324288 11:03:25 INFO - PROCESS | 1652 | 11:03:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:03:28 INFO - PROCESS | 1652 | RSS: Main: 205135872 11:03:28 INFO - PROCESS | 1652 | 11:03:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:03:30 INFO - PROCESS | 1652 | RSS: Main: 204410880 11:03:30 INFO - PROCESS | 1652 | 11:03:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:03:33 INFO - PROCESS | 1652 | RSS: Main: 204365824 11:03:33 INFO - PROCESS | 1652 | 11:03:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:03:36 INFO - PROCESS | 1652 | RSS: Main: 204357632 11:03:36 INFO - PROCESS | 1652 | 11:03:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:03:39 INFO - PROCESS | 1652 | RSS: Main: 205021184 11:03:39 INFO - PROCESS | 1652 | 11:03:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:03:43 INFO - PROCESS | 1652 | RSS: Main: 204709888 11:03:43 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:03:45 INFO - PROCESS | 1652 | RSS: Main: 206667776 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | __start_tp_report 11:03:45 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:03:45 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:03:45 INFO - PROCESS | 1652 | |i|pagename|runs| 11:03:45 INFO - PROCESS | 1652 | |0;ai-astar;108;121;119;120;119;119;119;120;120;121 11:03:45 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;110;112;147;109;111;111;110;135;142 11:03:45 INFO - PROCESS | 1652 | |2;audio-dft;197;198;225;281;244;197;193;199;194;195 11:03:45 INFO - PROCESS | 1652 | |3;audio-fft;82;59;61;87;62;65;61;50;84;85 11:03:45 INFO - PROCESS | 1652 | |4;audio-oscillator;90;69;86;71;71;70;81;72;71;79 11:03:45 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;90;88;105;112;88;89;102;119;135 11:03:45 INFO - PROCESS | 1652 | |6;imaging-darkroom;104;151;108;151;132;98;153;154;95;96 11:03:45 INFO - PROCESS | 1652 | |7;imaging-desaturate;91;98;91;92;94;89;90;94;90;94 11:03:45 INFO - PROCESS | 1652 | |8;json-parse-financial;66;65;65;67;65;67;68;66;66;67 11:03:45 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;57;65;61;66;64;58;53;56;71;91 11:03:45 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;80;82;85;85;99;102;102;82;96;100 11:03:45 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;162;149;148;145;144;136;135;142;184;134 11:03:45 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;165;163;163;167;167;165;166;166;168;164 11:03:45 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;64;66;66;63;65;67;67;63;62;65 11:03:45 INFO - PROCESS | 1652 | __end_tp_report 11:03:45 INFO - PROCESS | 1652 | __start_cc_report 11:03:45 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,978 11:03:45 INFO - PROCESS | 1652 | __end_cc_report 11:03:45 INFO - PROCESS | 1652 | __startTimestamp1493402625286__endTimestamp 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:03:45 INFO - PROCESS | 1652 | Number of tests: 14 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.60 Median:120.00 stddev:3.81 (3.2%) stddev-sans-first:0.83 11:03:45 INFO - PROCESS | 1652 | Values: 108.0 121.0 119.0 120.0 119.0 119.0 119.0 120.0 120.0 121.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:123.50 stddev:16.15 (13.1%) stddev-sans-first:15.73 11:03:45 INFO - PROCESS | 1652 | Values: 141.0 110.0 112.0 147.0 109.0 111.0 111.0 110.0 135.0 142.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:212.30 Median:198.50 stddev:29.33 (14.8%) stddev-sans-first:30.58 11:03:45 INFO - PROCESS | 1652 | Values: 197.0 198.0 225.0 281.0 244.0 197.0 193.0 199.0 194.0 195.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.60 Median:73.50 stddev:13.43 (18.3%) stddev-sans-first:13.48 11:03:45 INFO - PROCESS | 1652 | Values: 82.0 59.0 61.0 87.0 62.0 65.0 61.0 50.0 84.0 85.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:75.50 stddev:7.50 (9.9%) stddev-sans-first:6.00 11:03:45 INFO - PROCESS | 1652 | Values: 90.0 69.0 86.0 71.0 71.0 70.0 81.0 72.0 71.0 79.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:88.00 stddev:16.19 (18.4%) stddev-sans-first:16.51 11:03:45 INFO - PROCESS | 1652 | Values: 89.0 90.0 88.0 105.0 112.0 88.0 89.0 102.0 119.0 135.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:153.50 stddev:26.29 (17.1%) stddev-sans-first:26.85 11:03:45 INFO - PROCESS | 1652 | Values: 104.0 151.0 108.0 151.0 132.0 98.0 153.0 154.0 95.0 96.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:93.00 stddev:2.71 (2.9%) stddev-sans-first:2.83 11:03:45 INFO - PROCESS | 1652 | Values: 91.0 98.0 91.0 92.0 94.0 89.0 90.0 94.0 90.0 94.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 11:03:45 INFO - PROCESS | 1652 | Values: 66.0 65.0 65.0 67.0 65.0 67.0 68.0 66.0 66.0 67.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.50 stddev:10.86 (16.8%) stddev-sans-first:11.20 11:03:45 INFO - PROCESS | 1652 | Values: 57.0 65.0 61.0 66.0 64.0 58.0 53.0 56.0 71.0 91.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:83.50 stddev:9.23 (11.0%) stddev-sans-first:8.83 11:03:45 INFO - PROCESS | 1652 | Values: 80.0 82.0 85.0 85.0 99.0 102.0 102.0 82.0 96.0 100.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:146.50 stddev:15.14 (10.3%) stddev-sans-first:15.17 11:03:45 INFO - PROCESS | 1652 | Values: 162.0 149.0 148.0 145.0 144.0 136.0 135.0 142.0 184.0 134.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:1.71 (1.0%) stddev-sans-first:1.81 11:03:45 INFO - PROCESS | 1652 | Values: 165.0 163.0 163.0 167.0 167.0 165.0 166.0 166.0 168.0 164.0 11:03:45 INFO - PROCESS | 1652 | 11:03:45 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.50 stddev:1.75 (2.7%) stddev-sans-first:1.83 11:03:45 INFO - PROCESS | 1652 | Values: 64.0 66.0 66.0 63.0 65.0 67.0 67.0 63.0 62.0 65.0 11:03:45 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:03:45 INFO - PROCESS | 1652 | 11:03:46 INFO - TEST-INFO | 1652: exit 0 11:03:46 INFO - TEST-OK | kraken | took 59831ms 11:03:46 INFO - SUITE-END | took 1175s 11:03:46 INFO - Completed test suite (00:19:35) 11:03:46 INFO - javascript benchmark 11:03:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7984.977027961618, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2780.6663200415946, 3625.2205775758207, 3648.5986541480415, 3663.725220608455, 3690.7416480846337, 6181.443670069692, 6186.47414969727, 6209.565330426872, 6215.254169499666, 6216.627002379849, 6118.69406529674, 6203.565750397463, 6217.844553886155, 6221.7822376216845, 6241.375862413756, 5522.116461366192, 5593.74828132733, 5599.076152434864, 5616.185653080322, 5636.859078523049, 5628.240187574666, 5648.858778530549, 5652.152177173416, 5653.067243904751, 5654.519365853907, 5412.5940554458575, 5414.593905457087, 5558.749856256478, 5567.164925261202, 5617.9438205618035, 5592.468715472064, 5631.774729010835, 5635.239242702224, 5660.60375773696, 5694.971525142369, 5472.972635136839, 5569.972150139223, 5581.916271255935, 5603.327600687903, 5632.549396048306, 6005.399460054004, 6011.57918945674, 6047.727852246616, 6085.908711369333, 6211.503079753654, 6538.117354157175, 6571.2771595124495, 6576.802695919082, 6590.373914478117, 6591.077249185118, 6675.399214070709, 6679.465642748569, 6680.632565208912, 6689.096971908827, 6694.665266736643, 5592.356878958931, 6095.756169753249, 6248.218972628421, 6283.3088360280335, 6321.1782468278825, 6601.966990165019, 6603.834904127435, 6673.0, 6775.4240889524945, 6788.728450862009, 6517.4460170886005, 6542.836429089216, 6551.246606640202, 6555.6066636002, 6567.1462709848365, 6646.700898459484, 6662.900056499157, 6698.095757072733, 6711.798646040529, 6737.764178253738, 6289.371062893674, 6332.461740752087, 6340.953742632506, 6594.604323740591, 6645.102911106938, 6437.259715132726, 6491.480681545465, 6512.088307636935, 6515.51133664977, 6533.934660653427, 4046.2716710992304, 4497.8425755098415, 4870.464248932614, 4876.000419913909, 5334.893302133935, 4630.814767409333, 4693.5071817458675, 4708.788104535304, 4731.408573928259, 4731.574158325767, 6243.625382477066, 6284.874302513924, 6291.182146320949, 6323.177986861679, 6371.076193951851, 5572.693501857437, 6715.731370745116, 6728.259891411941, 6746.359095885981, 6760.7295708172105, 5289.68261904287, 6358.1734374531015, 6390.680465976682, 6399.456046236122, 6410.775622853178, 5614.6631202128, 5682.176084467729, 5714.913946468861, 5789.363170051291, 5819.447152520504, 6885.0, 6927.68825402848, 6953.33943275388, 6954.965225173842, 6962.990366396945, 6648.401643852077, 6665.400113989765, 6669.833254168685, 6670.366315200048, 6693.665316734241, 6592.24189218246, 6694.230163531256, 6804.149481314836, 6860.622665753432, 6971.51199416036, 6476.352364763486, 6493.155889734493, 6537.05212744159, 6538.574992625464, 6546.116274302908, 5550.611457197957, 6375.776847810304, 6386.393292637192, 6483.935160648333, 6639.568428052355, 6246.219222597176, 6395.808125756234, 6405.615663060045, 6407.391297826699, 6412.711427985845, 5477.643953143033, 6261.373862613702, 6292.748290068346, 6496.70764815575, 6523.282438931618, 6143.051335359142, 6248.750049998131, 6302.401271879165, 6318.557700961072, 6335.556511044183, 6495.935040649722, 6508.088867558451, 6515.087887695821, 6522.05430212607, 6524.4454221389815, 6238.812835614939, 6300.527460440577, 6342.682865856781, 6370.4903607712195, 6373.458256048288], "value": 6047.573352765922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.9329407000405, 830.551502188818, 857.502648463891, 986.5166068626376, 988.6589126751269, 31907.68092319076, 42552.0, 42658.72023839282, 42795.57204427955, 42811.35782963254, 41362.58637413625, 41376.0, 41469.0, 41557.0, 41572.0, 8343.040550336713, 8476.87284690727, 11326.32042077478, 11577.247478913843, 11751.647450576455, 11374.0, 11380.089592832554, 11423.543058277659, 11480.425978701074, 11511.942440287787, 11438.0, 11464.71338216543, 11475.827862582068, 11489.138314626394, 11500.079993600491, 11343.205975581712, 11347.489362978686, 11349.943250283737, 11401.885981140165, 11443.942780286128, 11127.165462590337, 11178.776424471547, 11183.83224251637, 11203.159763017806, 11207.383593902332, 36594.81702591496, 36609.901702948955, 36620.53517859282, 36769.448458273146, 36788.44817327742, 3939.4090886367276, 3943.743656662308, 3974.5627980922077, 3990.4213888985932, 4039.434479172918, 21222.681659775117, 26367.0, 26486.73513264862, 27719.86140069307, 28171.0, 3155.952660710091, 3207.4547326954475, 3242.254281515241, 3257.1531401835455, 3333.933321333584, 323688.0, 348236.7764483535, 348960.0, 350109.0, 353371.0, 38628.0, 46398.0, 46465.5353446468, 46503.0, 46574.76712616415, 48427.27359089616, 48685.756571216916, 48694.0, 48702.0, 48822.511774881794, 14893.255337233095, 14944.178070206242, 15046.924765376103, 15060.849391506164, 15086.773698394532, 12706.174098683556, 12829.871701283055, 12831.743365132645, 12857.485700571873, 13062.085654004128, 11494.94252528732, 12199.634010979686, 12248.265104093605, 12326.260424374566, 12416.379181040911, 5379.408265090837, 5389.730513474389, 5395.325584301962, 5762.827115186551, 5807.216025836543, 1136.545381847251, 1172.4841069929205, 1189.7501524679915, 1207.9490842966675, 1230.698478872682, 23064.769352306597, 27429.86285068562, 27725.58411623827, 27846.74689638971, 27894.442111157663, 1285.3252042677668, 1299.389287035092, 1306.1901620995043, 1341.7182391697659, 1343.7782765843722, 14044.438222471192, 14291.499797507246, 14341.426342946374, 14564.053336533092, 14632.414703411958, 366104.0, 448619.75690121995, 449994.0, 451904.0, 454512.0, 54914.725426373414, 61281.69359153265, 61523.0, 61544.0, 61733.45666358377, 1459.6423876150411, 1482.7479328514178, 1499.3253036133783, 1499.550134959508, 1501.1743541052597, 28207.43585128327, 28530.85734571314, 28926.409047502588, 29371.265718357212, 29605.85197074001, 44398.11203775971, 44432.77783611061, 44484.77757611191, 44563.77718111389, 44571.0, 408708.0, 410224.8977510306, 410719.0, 410727.0, 410945.0, 30328.54507182438, 30710.385792284476, 30900.918467854415, 30998.690013099582, 31205.90779322713, 39369.40945885871, 40056.39915401213, 40616.79691601523, 40925.0, 41104.58895411008, 28375.723092461303, 28584.428311434072, 29337.266568335108, 29521.704782952755, 29770.70229297679, 28505.42989140164, 28607.570886437137, 29361.265968350963, 29407.558886617146, 29724.554131688474, 33638.32723345471, 33722.32555348928, 34333.79831705878, 34354.48468272928, 34391.4841277376, 28055.456949867137, 28510.0, 28656.856715716287, 28686.852525899565, 29479.705202947694, 18435.80167289122, 19019.90490047541, 19500.0, 23819.285421436693, 23833.046678133374], "value": 18858.82338941503, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.1652951128102, 443.03418547566304, 443.8934655682637, 452.19283578811815, 460.64990607138566, 18413.907930460347, 20832.270870519536, 22266.777332226695, 22591.322260332174, 22934.65598016031, 20163.58854880159, 21140.682889756667, 22721.886390568023, 22881.771182288172, 23701.40746481339, 6884.173899132111, 7035.226125126232, 7074.575525468463, 7163.14042314923, 7172.784816455514, 6360.395762402564, 6819.386255237027, 7148.3209095136135, 7165.390941769956, 7172.462065345094, 8022.719204827832, 8093.988251468566, 8108.878366824473, 8112.107668156498, 8127.187281271885, 7818.6872525098925, 7985.321247693924, 7994.680212791482, 8068.515889046617, 8086.5148091114725, 7686.846263074764, 7755.728549500796, 7808.804779880492, 7815.531068135873, 7869.0, 26605.733942660518, 26918.86540567304, 27179.912803487838, 27604.585931211048, 27724.861375693195, 2914.6793852676187, 2993.2965753048015, 3076.5385192221124, 3094.7524198064325, 3097.396007778484, 13826.0, 15864.46091274304, 15864.60338491547, 16632.25154868034, 16656.250468728816, 2100.5798840232014, 2121.5733015494575, 2142.2930432957087, 2157.8057974782346, 2164.1235299703644, 156551.0, 159702.0, 164344.17827910904, 168286.0, 168349.3165068358, 22503.88748056249, 22558.548829023326, 22680.319590411986, 22922.96846641905, 22969.540609187723, 33556.664433355356, 33720.32559348848, 34176.48735268973, 34254.48618270728, 34267.800626977936, 10873.0, 11046.502907369188, 11192.216544841946, 11197.832032519518, 11394.601188958346, 7705.653245603849, 9331.0, 9344.299177561574, 9358.251339892793, 9507.477088760184, 7541.909141873374, 9933.751656208653, 10028.448435336126, 10251.436171010666, 10304.381737095799, 4325.52419233884, 4377.5403582624, 4399.846005389796, 4417.050334178167, 4421.38100665907, 798.594473726246, 927.1331305229631, 939.2110627073291, 953.0612346838359, 982.5676702250987, 18307.0, 20992.992396004418, 21665.891670541863, 21751.021204045857, 22035.449113772283, 883.293365307764, 922.2760133295465, 967.8064387122604, 987.2940847294219, 989.1443901025561, 11021.614243501439, 11312.490937907649, 11339.716507087389, 11381.146414018816, 11564.826527602092, 689092.0, 706816.0, 710128.0, 717039.4148029226, 720979.0, 129637.70362296444, 161682.0, 163406.1829690844, 164408.0, 165921.0, 1244.3840299052026, 1256.6795467155816, 1273.3187744556617, 1275.942582583786, 1277.2911034375825, 18883.811161888207, 19095.618087638446, 19209.519762005504, 19231.03844807726, 19472.61054778868, 64245.0, 64557.67721161364, 64612.676936616896, 64947.0, 66651.0, 90520.0, 98424.0, 99400.0, 100063.99936000547, 101196.49401752945, 7718.459707820399, 10522.894771052192, 11792.115591330856, 12139.332336721565, 12166.45250963691, 12770.553030643883, 12897.29064901402, 13010.544630937871, 13312.800307995582, 13525.323733813073, 11344.149188810707, 11396.715082123013, 11744.236624619372, 11748.060155187735, 11892.524299027942, 10362.948185259025, 10528.210384221367, 10615.628453004108, 10695.197860160364, 10843.67468975932, 11582.536698531965, 11734.0, 12021.579244726392, 12070.0, 12070.3361315125, 10679.305845120043, 11833.526658933546, 11860.644180674593, 12054.457549410474, 12288.938555307166, 11302.604408845651, 11389.658310250707, 11446.255993360404, 11793.29240245588, 11826.994705450134], "value": 12037.863279788739, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1617.417729617338, 1637.1895911523798, 1668.2242757117945, 1678.6978343898102, 1684.3094331324155, 10941.234113612041, 11546.076313894868, 11613.129015323853, 11702.707432314197, 11941.223820451665, 9640.517974101285, 11436.942815285933, 11630.592929247458, 11632.0, 11710.473028713706, 2078.760942491614, 2507.912223072193, 2511.1713134665515, 2513.6606558114695, 2518.9244322670347, 2429.6841410616685, 2494.5135698538793, 2494.663220465232, 2508.0093363121555, 2512.6231065340253, 2300.424893776556, 2307.653851922208, 2372.478054827935, 2494.8627825469594, 2508.4606809535944, 2027.929022484206, 2465.543874383227, 2492.015653816741, 2496.8876400561926, 2499.237732491589, 2449.730529641738, 2465.827392082555, 2480.355107672, 2483.6026235802183, 2497.600383938561, 11151.553937842476, 11350.375729334883, 11352.375619340934, 11362.0, 11365.261258018203, 5999.76000959961, 6100.572959892809, 6154.72303746328, 6157.168782214389, 6168.7224074916285, 3653.7442379033478, 3715.0, 3783.9621603783885, 3789.0716774390175, 3859.5754467008605, 1275.483429211171, 1838.1360760442572, 1838.3473866777235, 1859.1355019915668, 1935.525796179945, 12996.675083122904, 13503.392347344394, 13817.723645527034, 15417.922910385489, 15775.842241577551, 3965.5637879833193, 4768.0702263059065, 4840.273958906122, 5001.5498605124985, 5039.966806804597, 12131.393430328448, 12141.69645758863, 12146.149769516225, 12178.208416452902, 12289.893909548184, 6068.787592434243, 6127.662978536134, 6127.785527506605, 6129.693515324216, 6139.723712432951, 5365.785368585291, 5476.151196564539, 5485.122380419193, 5497.8075767347955, 5627.099664053731, 4061.1268577256033, 4105.466289382421, 4109.938350924738, 4178.603032711888, 4206.158768246363, 932.654917680449, 1088.6679562733443, 1096.6107032003524, 1101.8347247912716, 1109.328856042099, 983.2478154211891, 990.4107056301489, 1001.8196724589498, 1011.7066050845319, 1017.9592816287413, 5445.809396671177, 6681.5656982296, 6772.661366931634, 6792.660366981631, 6940.09778728762, 998.6354980432089, 1029.2949329709024, 1036.7459972306833, 1044.8497779195732, 1086.266769930294, 3778.6599206071587, 4254.404598275647, 4273.67947403946, 4473.1277400907165, 4512.751798651106, 13428.19430834153, 13642.181469111885, 13961.092528985584, 13962.301884905713, 13980.72038559223, 13587.320633968262, 13953.930230348784, 13966.162030278216, 13969.092009019381, 14261.928690356483, 8967.148120928632, 10208.43853588045, 10481.109105725945, 10496.0, 10521.3687178768, 6705.865882682417, 6948.235694073547, 7290.744823931137, 7320.560766354036, 7324.523905946096, 11913.22564033335, 12148.0, 12239.632811015685, 12303.261804291413, 12430.192037517532, 12592.937035314764, 12716.537598176328, 12726.872731272939, 12763.680907977376, 12926.870731292567, 8325.375596830141, 8426.536540490331, 8497.702580409903, 8499.447535910145, 8517.403781735467, 10295.897041029493, 10398.844017339898, 10524.210684198562, 10526.000029997138, 10537.15702743794, 8857.335699822668, 8952.28381729447, 9024.36829421934, 9029.864552031857, 9167.17495425415, 8707.390482666153, 8807.119288071142, 8815.735527934172, 8858.77853053653, 8859.158379953895, 8018.959905200437, 8158.959205203936, 8179.182081791773, 8190.099089100075, 8198.959005204935, 5928.288605367384, 5967.46292833647, 5989.760409583568, 6065.968785306597, 6110.663913484801, 6029.457348838626, 6082.178905847831, 6217.4715149212825, 6223.128761973418, 6329.398707122816], "value": 5319.766358985899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.7901101921489, 401.24966312994684, 403.17952965714767, 406.7254603142879, 407.70848843077215, 4705.694129881555, 5236.738163091844, 5396.001739678162, 5415.0, 5433.5768465866495, 4313.633341166004, 4766.380370551832, 5273.314469119019, 5279.234510995903, 5293.338332708411, 3632.8365223565, 4536.412255710508, 4538.865283679081, 4559.886002849922, 4578.58792708657, 4027.597240275978, 4547.954520454803, 4587.42657167854, 4596.494385617596, 4597.747123908183, 3533.3639944809925, 3814.332491813935, 3952.367621180612, 4115.71190016699, 4276.524599013335, 3677.0991107178643, 3926.7447615904875, 4336.980809509762, 4359.651227901792, 4433.7118087324225, 3422.640622734602, 3728.45937339087, 3854.69162467002, 3860.9227815443533, 4031.629246056355, 4430.579094986004, 4757.09615173116, 4811.711297322172, 5251.238570407269, 5306.814261500829, 4912.238603016538, 5076.923846142311, 5116.565091967187, 5423.718650216601, 5431.429699881495, 4979.979104283613, 5266.894662106774, 5358.955003774266, 5623.3289187635955, 5880.235569375996, 4457.955420445786, 4484.8430304939175, 4754.477007529169, 4999.275105109738, 5041.647084704072, 5645.407232240637, 5811.854703632401, 5885.499197704558, 5964.910526342108, 5990.311114221877, 4261.4673165854265, 4454.710443821141, 5066.316047333563, 5161.0, 5181.53366197044, 5307.734613269398, 5328.7121196987155, 5332.893342133136, 5335.0, 5360.276362691065, 5112.284280200774, 5418.51233388989, 5490.423505531941, 5541.010472459822, 5564.27664403633, 4931.605471562267, 5729.742161602738, 5753.539716822728, 5763.452472015152, 5859.220802582396, 1653.9917300413422, 1818.4362847517311, 1822.88151270167, 1833.6247814139394, 1840.7514985477058, 1391.1428243295313, 1428.286056731905, 1483.8961272710806, 1492.5522343296968, 1510.8262549806693, 4591.7602247393015, 5151.9484805152215, 5162.535371816554, 5316.468353164652, 5318.596098496965, 5189.506996835295, 5260.947810437851, 5443.148615238634, 5887.852803679943, 5959.404059594092, 4390.253656878338, 5124.948750512522, 5274.551663108678, 5449.945500545023, 5461.672299662033, 2685.4092099738027, 3066.635347270443, 3077.892273770452, 3084.0131158029662, 3104.9534256986162, 5273.709945952933, 5631.551074651062, 5808.838232353546, 6242.070033468223, 6262.937370626236, 6049.576529642883, 6122.53059265773, 6144.695739097839, 6150.831342045087, 6163.784267550704, 5675.4040825714355, 6205.6586887720705, 6214.409631084949, 6220.844478887973, 6278.515423939666, 5491.368492623399, 5892.731298450213, 5897.292324921037, 5903.734331954986, 5954.404559544011, 5622.803201887914, 5868.911966320594, 5878.589778190922, 5891.23413956183, 5893.911591326219, 4762.119007983396, 5943.195041677169, 6041.33863187627, 6095.542834287357, 6114.38856114385, 4239.982404223026, 4503.909921801611, 4635.652326075625, 4695.548771652764, 4726.243800991825, 4587.779243096293, 5777.277840269966, 5891.674373265981, 5969.164316995537, 5980.37206093354, 5207.609429292743, 5474.562035037108, 5484.91772623403, 5681.375048744552, 5720.856978575569, 5702.002149623716, 5709.8287051388525, 5711.628744131618, 5723.570732195185, 5727.942720572741, 5100.617453690914, 5320.52115309624, 5505.807296744595, 5526.392096869422, 5528.944710552843, 4938.358013458227, 5420.970015697163, 5714.085746280575, 5781.335146458211, 5793.478586927028, 5294.352823588206, 5349.679019258857, 5396.64921780083, 5581.74882130297, 5640.266765320482], "value": 4466.380847241643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.7803276066322, 777.3314949143739, 811.7037281392292, 812.3947658994047, 918.4397517514317, 15870.523884283475, 17807.198676059576, 18019.189136488858, 18033.458996230118, 18058.91646501211, 15898.920505397486, 16523.83476165238, 16550.172491375444, 16620.33518659255, 16643.084630345325, 993.9652112176075, 1013.8479228115768, 1013.878334599849, 1016.3343010328247, 1018.4195008844962, 954.0840792838849, 988.9011098890089, 1000.7298029532022, 1000.7348052766023, 1002.2683441087974, 1053.2521909444265, 1067.7971185474773, 1068.0707784227732, 1068.2628985999675, 1069.03786592067, 1041.5417216424748, 1061.0556604621893, 1062.229883334584, 1063.4470075560703, 1063.8563793887843, 778.2567647896245, 790.4387884601939, 791.2404092071619, 792.6472719639762, 793.9206079392072, 17465.7380139298, 17517.737233941374, 17610.471685849316, 18074.728879066824, 18166.091695415307, 14738.778918316233, 14777.778333325008, 14825.629359266104, 14828.925855370762, 14897.255137243203, 12106.576269830603, 12255.816162757566, 12257.93871030648, 12341.876581234252, 12477.688057798538, 3783.0, 3802.2775672622115, 3885.2812229737306, 3919.7648141111627, 4029.3351596986463, 114184.71630567426, 117026.41486792597, 117997.0, 118471.99999999914, 120381.0, 9460.763480912894, 9721.881983571839, 11207.775844483065, 11343.205975581795, 11354.772904541864, 22830.086796527954, 22929.853507324566, 23221.88389058044, 23258.0, 23259.53480930406, 8132.390070744717, 8191.754247372588, 8206.384521160937, 8210.507369557845, 8259.884915536446, 4404.273294906368, 4496.595306422372, 4557.544245575482, 4565.315202719618, 4627.375304333939, 3690.2988432197794, 3727.38498147808, 3797.7911214882893, 3802.3535998880257, 3822.50307460034, 671.3615491119946, 674.5379415100672, 683.7504310926575, 688.1948120698794, 691.1360799001249, 690.6822861483773, 708.450950513346, 721.1742554774753, 734.1410549656914, 772.8377040821377, 9199.724008279762, 10003.099721025143, 10634.14926805869, 10929.672109836718, 10952.890471095187, 694.9096617439801, 705.386313906894, 717.9030830837875, 740.5519660605364, 747.5776186454635, 5185.092608793446, 5296.682199068068, 5305.151175811851, 5310.522053015247, 5317.41508434072, 108457.0, 108938.45530772454, 108962.0, 109931.45034274778, 112073.0, 90604.0, 92175.53912230396, 92548.53725731328, 93963.0603693968, 94502.0549794507, 76193.61903190338, 81545.77681334785, 82157.0, 82364.0, 83429.0, 9646.807063858823, 9694.56374463165, 9850.965648606792, 10154.95585126228, 11166.553337866719, 35348.646513534535, 36353.091172720895, 46884.53115468802, 48196.759016206095, 49762.0, 59559.1066134017, 59579.0, 61150.69424652848, 61846.0723089163, 62574.68712656407, 11202.719932001764, 11585.710357241136, 11726.824097638371, 12120.333381664093, 13213.339333033118, 13069.673258168621, 13357.398917048562, 15013.624659383166, 15291.847081529042, 15671.608209794846, 14006.43974241019, 14122.364493598017, 14458.589500916325, 15040.17279049619, 15345.923270383577, 14182.574522764333, 14367.28163591837, 14711.55865324042, 14866.256687165382, 14876.330565124379, 12196.756064878475, 13611.455541778218, 13847.0, 14120.858791411954, 14198.645033874236, 9980.600775969171, 10425.843612345669, 10470.581176752845, 10481.109105726098, 10502.264841461025, 8779.063705557353, 9249.630014799333, 11420.455109804336, 11629.302241865515, 11632.88367116318], "value": 7945.302923241705, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 119.0, 120.0, 119.0, 119.0, 119.0, 120.0, 120.0, 121.0], "value": 118.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 112.0, 147.0, 109.0, 111.0, 111.0, 110.0, 135.0, 142.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 225.0, 281.0, 244.0, 197.0, 193.0, 199.0, 194.0, 195.0], "value": 212.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 87.0, 62.0, 65.0, 61.0, 50.0, 84.0, 85.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 86.0, 71.0, 71.0, 70.0, 81.0, 72.0, 71.0, 79.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 105.0, 112.0, 88.0, 89.0, 102.0, 119.0, 135.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 151.0, 108.0, 151.0, 132.0, 98.0, 153.0, 154.0, 95.0, 96.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 91.0, 92.0, 94.0, 89.0, 90.0, 94.0, 90.0, 94.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 67.0, 65.0, 67.0, 68.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 61.0, 66.0, 64.0, 58.0, 53.0, 56.0, 71.0, 91.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 85.0, 85.0, 99.0, 102.0, 102.0, 82.0, 96.0, 100.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 149.0, 148.0, 145.0, 144.0, 136.0, 135.0, 142.0, 184.0, 134.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 163.0, 167.0, 167.0, 165.0, 166.0, 166.0, 168.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 63.0, 65.0, 67.0, 67.0, 63.0, 62.0, 65.0], "value": 64.8, "unit": "score"}]}]} 11:03:46 INFO - Return code: 0 11:03:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:03:46 ERROR - # TBPL SUCCESS # 11:03:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:03:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:03:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:03:46 INFO - 2017-04-28 11:03:46.643 screenresolution[1657:10589] starting screenresolution argv=screenresolution get 11:03:46 INFO - 2017-04-28 11:03:46.645 screenresolution[1657:10589] Display 0: 1600x1200x32@60 11:03:46 INFO - 2017-04-28 11:03:46.651 screenresolution[1658:10592] starting screenresolution argv=screenresolution list 11:03:46 INFO - Available Modes on Display 0 11:03:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:03:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:03:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:03:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:03:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:03:46 INFO - Intel Iris: 11:03:46 INFO - Chipset Model: Intel Iris 11:03:46 INFO - Type: GPU 11:03:46 INFO - Bus: Built-In 11:03:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:03:46 INFO - Vendor: Intel (0x8086) 11:03:46 INFO - Device ID: 0x0a2e 11:03:46 INFO - Revision ID: 0x0009 11:03:46 INFO - Displays: 11:03:46 INFO - Display: 11:03:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:03:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:03:46 INFO - Display Serial Number: 1600x1200 60 11:03:46 INFO - Main Display: Yes 11:03:46 INFO - Mirror: Off 11:03:46 INFO - Online: Yes 11:03:46 INFO - Rotation: Supported 11:03:46 INFO - Return code: 0 11:03:46 INFO - Running post-action listener: _resource_record_post_action 11:03:46 INFO - [mozharness: 2017-04-28 18:03:46.895866Z] Finished run-tests step (success) 11:03:46 INFO - Running post-run listener: _resource_record_post_run 11:03:46 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 41207808; Write bytes: 427128832; Read time: 394; Write time: 1440 11:03:46 INFO - TinderboxPrint: CPU usage
54.4% 11:03:46 INFO - TinderboxPrint: I/O read bytes / time
41,207,808 / 394 11:03:46 INFO - TinderboxPrint: I/O write bytes / time
427,128,832 / 1,440 11:03:46 INFO - TinderboxPrint: CPU idle
2,172.7 (45.6%) 11:03:46 INFO - TinderboxPrint: CPU system
953.2 (20.0%) 11:03:46 INFO - TinderboxPrint: CPU user
1,639.7 (34.4%) 11:03:46 INFO - TinderboxPrint: Swap in / out
163,540,992 / 0 11:03:46 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 110742016; Write bytes: 105863168; Read time: 9897; Write time: 262 11:03:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 40241152; Write bytes: 301477888; Read time: 379; Write time: 1125 11:03:47 INFO - Running post-run listener: _upload_blobber_files 11:03:47 INFO - Blob upload gear active. 11:03:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:03:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:03:47 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:03:47 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:03:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:03:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:03:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:48 INFO - (blobuploader) - INFO - Done attempting. 11:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:03:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:49 INFO - (blobuploader) - INFO - Done attempting. 11:03:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:03:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:03:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:50 INFO - (blobuploader) - INFO - Done attempting. 11:03:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:03:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:03:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:03:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:03:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:03:53 INFO - (blobuploader) - INFO - Done attempting. 11:03:53 INFO - (blobuploader) - INFO - Iteration through files over. 11:03:53 INFO - Return code: 0 11:03:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:03:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:03:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04f915db5cd5cb28833f038760373c7079836d6d255c1190f463590661e9fc4f41159d7b56b5e6ab16f84381dc5ec563803fbeec01113b9e8d5de8a9f2341ddb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58f4114b6f8c04d09e07e7cc03d4214824911194a292e4b758f4550f91dead4182f61da4ffde1f94de6fc4683f17ff3d37d302f0ace739f83700ecddab6bd670", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28bc002e5cd9cd95742f0d17d81d5dff0bb7d233b603b0da92add089602068da6c827edd512d2323dd942de697f164ff7c14b003394a9bfa42b96a2ffb225580", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d414c5577b7a0c20bcb57b328c795a0aa6e6c5b70217ed22fcb85a07a54b6e9fa389fdf6733c4a57651c50eda25ec7eb9cfffb50f46a0c4aed2af609ba06b60"} 11:03:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:03:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:03:53 INFO - Contents: 11:03:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04f915db5cd5cb28833f038760373c7079836d6d255c1190f463590661e9fc4f41159d7b56b5e6ab16f84381dc5ec563803fbeec01113b9e8d5de8a9f2341ddb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58f4114b6f8c04d09e07e7cc03d4214824911194a292e4b758f4550f91dead4182f61da4ffde1f94de6fc4683f17ff3d37d302f0ace739f83700ecddab6bd670", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28bc002e5cd9cd95742f0d17d81d5dff0bb7d233b603b0da92add089602068da6c827edd512d2323dd942de697f164ff7c14b003394a9bfa42b96a2ffb225580", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d414c5577b7a0c20bcb57b328c795a0aa6e6c5b70217ed22fcb85a07a54b6e9fa389fdf6733c4a57651c50eda25ec7eb9cfffb50f46a0c4aed2af609ba06b60"} 11:03:53 INFO - Running post-run listener: copy_logs_to_upload_dir 11:03:53 INFO - Copying logs to upload dir... 11:03:53 INFO - mkdir: /builds/slave/test/build/upload/logs 11:03:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.988228 ========= master_lag: 1.69 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-04-28 11:03:55.422332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 11:03:55.425635) ========= 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.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04f915db5cd5cb28833f038760373c7079836d6d255c1190f463590661e9fc4f41159d7b56b5e6ab16f84381dc5ec563803fbeec01113b9e8d5de8a9f2341ddb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58f4114b6f8c04d09e07e7cc03d4214824911194a292e4b758f4550f91dead4182f61da4ffde1f94de6fc4683f17ff3d37d302f0ace739f83700ecddab6bd670", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28bc002e5cd9cd95742f0d17d81d5dff0bb7d233b603b0da92add089602068da6c827edd512d2323dd942de697f164ff7c14b003394a9bfa42b96a2ffb225580", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d414c5577b7a0c20bcb57b328c795a0aa6e6c5b70217ed22fcb85a07a54b6e9fa389fdf6733c4a57651c50eda25ec7eb9cfffb50f46a0c4aed2af609ba06b60"} build_url:https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010865 build_url: 'https://queue.taskcluster.net/v1/task/ZZoVjBL0TqyzLHY8hv-T3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04f915db5cd5cb28833f038760373c7079836d6d255c1190f463590661e9fc4f41159d7b56b5e6ab16f84381dc5ec563803fbeec01113b9e8d5de8a9f2341ddb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58f4114b6f8c04d09e07e7cc03d4214824911194a292e4b758f4550f91dead4182f61da4ffde1f94de6fc4683f17ff3d37d302f0ace739f83700ecddab6bd670", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28bc002e5cd9cd95742f0d17d81d5dff0bb7d233b603b0da92add089602068da6c827edd512d2323dd942de697f164ff7c14b003394a9bfa42b96a2ffb225580", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1d414c5577b7a0c20bcb57b328c795a0aa6e6c5b70217ed22fcb85a07a54b6e9fa389fdf6733c4a57651c50eda25ec7eb9cfffb50f46a0c4aed2af609ba06b60"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 11:03:55.464347) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 11:03:55.464699) ========= 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.XCvCzwEBtK/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.NZiiTQuXan/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005119 ========= master_lag: 0.29 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 11:03:55.756798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 11:03:55.757151) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 11:03:55.757538) ========= ========= Total master_lag: 2.07 =========