builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0341 starttime: 1487265376.85 results: success (0) buildid: 20170216073239 builduid: 7b2665f36be9484581f0c71611117a12 revision: 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:16.850022) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:16.850449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:16.850723) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:16.875044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:16.875399) ========= 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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-02-16 09:16:16-- 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% 190M=0s 2017-02-16 09:16:16 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104128 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:17.002573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:17.002901) ========= 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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.036459 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:17.061794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 09:16:17.062139) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 --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-aurora --rev 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-02-16 09:16:17,153 truncating revision to first 12 chars 2017-02-16 09:16:17,153 Setting DEBUG logging. 2017-02-16 09:16:17,153 attempt 1/10 2017-02-16 09:16:17,153 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3afbdf81cbf9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 09:16:18,484 unpacking tar archive at: mozilla-aurora-3afbdf81cbf9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.833315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 09:16:18.918719) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:18.919097) ========= script_repo_revision: 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:18.919473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:18.919739) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 09:16:18.939147) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 09:16:18.939505) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:16:19 INFO - MultiFileLogger online at 20170216 09:16:19 in /builds/slave/test 09:16:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:16:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:16:19 INFO - {'append_to_log': False, 09:16:19 INFO - 'base_work_dir': '/builds/slave/test', 09:16:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:16:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:16:19 INFO - 'branch': 'Mozilla-Aurora', 09:16:19 INFO - 'buildbot_json_path': 'buildprops.json', 09:16:19 INFO - 'config_files': ('talos/mac_config.py',), 09:16:19 INFO - 'default_actions': ('clobber', 09:16:19 INFO - 'read-buildbot-config', 09:16:19 INFO - 'download-and-extract', 09:16:19 INFO - 'populate-webroot', 09:16:19 INFO - 'create-virtualenv', 09:16:19 INFO - 'install', 09:16:19 INFO - 'run-tests'), 09:16:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:16:19 INFO - 'download_minidump_stackwalk': True, 09:16:19 INFO - 'download_symbols': 'ondemand', 09:16:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:16:19 INFO - 'tooltool.py': '/tools/tooltool.py', 09:16:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:16:19 INFO - '/tools/misc-python/virtualenv.py')}, 09:16:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:16:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:16:19 INFO - 'installer_path': 'installer.exe', 09:16:19 INFO - 'log_level': 'info', 09:16:19 INFO - 'log_name': 'talos', 09:16:19 INFO - 'log_to_console': True, 09:16:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:16:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:16:19 INFO - 'opt_config_files': (), 09:16:19 INFO - 'pip_index': False, 09:16:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:19 INFO - 'cmd': ('bash', 09:16:19 INFO - '-c', 09:16:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:19 INFO - 'enabled': True, 09:16:19 INFO - 'halt_on_failure': False, 09:16:19 INFO - 'name': 'check_screen_resolution'},), 09:16:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:16:19 INFO - 'cmd': ('bash', 09:16:19 INFO - '-c', 09:16:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:16:19 INFO - 'enabled': True, 09:16:19 INFO - 'halt_on_failure': False, 09:16:19 INFO - 'name': 'check_screen_resolution'},), 09:16:19 INFO - 'run_cmd_checks_enabled': True, 09:16:19 INFO - 'sps_profile': False, 09:16:19 INFO - 'sps_profile_interval': 0, 09:16:19 INFO - 'suite': 'dromaeojs-e10s', 09:16:19 INFO - 'system_bits': '32', 09:16:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:16:19 INFO - 'title': 't-yosemite-r7-0341', 09:16:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:16:19 INFO - 'use_talos_json': True, 09:16:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:16:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:16:19 INFO - 'work_dir': 'build'} 09:16:19 INFO - [mozharness: 2017-02-16 17:16:19.152789Z] Running clobber step. 09:16:19 INFO - Running pre-action listener: _resource_record_pre_action 09:16:19 INFO - Running main action method: clobber 09:16:19 INFO - rmtree: /builds/slave/test/build 09:16:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:16:20 INFO - Running post-action listener: _resource_record_post_action 09:16:20 INFO - [mozharness: 2017-02-16 17:16:20.438921Z] Finished clobber step (success) 09:16:20 INFO - [mozharness: 2017-02-16 17:16:20.439086Z] Running read-buildbot-config step. 09:16:20 INFO - Running pre-action listener: _resource_record_pre_action 09:16:20 INFO - Running main action method: read_buildbot_config 09:16:20 INFO - Using buildbot properties: 09:16:20 INFO - { 09:16:20 INFO - "project": "", 09:16:20 INFO - "product": "firefox", 09:16:20 INFO - "script_repo_revision": "production", 09:16:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:16:20 INFO - "repository": "", 09:16:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:16:20 INFO - "buildid": "20170216073239", 09:16:20 INFO - "pgo_build": "False", 09:16:20 INFO - "basedir": "/builds/slave/test", 09:16:20 INFO - "buildnumber": 63, 09:16:20 INFO - "slavename": "t-yosemite-r7-0341", 09:16:20 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:16:20 INFO - "platform": "yosemite_r7", 09:16:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:16:20 INFO - "branch": "mozilla-aurora", 09:16:20 INFO - "repo_path": "releases/mozilla-aurora", 09:16:20 INFO - "revision": "3afbdf81cbf92638d71dfe6b159ed2de69cdda62", 09:16:20 INFO - "stage_platform": "macosx64", 09:16:20 INFO - "builduid": "7b2665f36be9484581f0c71611117a12", 09:16:20 INFO - "slavebuilddir": "test" 09:16:20 INFO - } 09:16:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 09:16:20 INFO - Running post-action listener: _resource_record_post_action 09:16:20 INFO - [mozharness: 2017-02-16 17:16:20.442112Z] Finished read-buildbot-config step (success) 09:16:20 INFO - [mozharness: 2017-02-16 17:16:20.442290Z] Running download-and-extract step. 09:16:20 INFO - Running pre-action listener: _resource_record_pre_action 09:16:20 INFO - Running main action method: download_and_extract 09:16:20 INFO - mkdir: /builds/slave/test/build/tests 09:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:20 INFO - trying https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:20 INFO - Downloading https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 09:16:21 INFO - Downloaded 1199 bytes. 09:16:21 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 09:16:21 INFO - Using the following test package requirements: 09:16:21 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:16:21 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 09:16:21 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 09:16:21 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'jsshell-mac.zip'], 09:16:21 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 09:16:21 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 09:16:21 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 09:16:21 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 09:16:21 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 09:16:21 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 09:16:21 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 09:16:21 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:16:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:16:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:16:21 INFO - Fetch https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:16:22 INFO - Expected file size: 16358274 09:16:22 INFO - Obtained file size: 16358274 09:16:28 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:16:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 09:16:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:16:28 INFO - Fetch https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 09:16:29 INFO - Expected file size: 16358274 09:16:29 INFO - Obtained file size: 16358274 09:16:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 09:16:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:16:36 INFO - Fetch https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 09:16:36 INFO - Expected file size: 14403485 09:16:36 INFO - Obtained file size: 14403485 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:37 INFO - trying https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:16:37 INFO - Downloading https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 09:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 09:16:39 INFO - Downloaded 73946275 bytes. 09:16:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:16:39 INFO - mkdir: /builds/slave/test/properties 09:16:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:39 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:39 INFO - Contents: 09:16:39 INFO - build_url:https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 09:16:40 INFO - Running post-action listener: _resource_record_post_action 09:16:40 INFO - Running post-action listener: set_extra_try_arguments 09:16:40 INFO - [mozharness: 2017-02-16 17:16:40.036047Z] Finished download-and-extract step (success) 09:16:40 INFO - [mozharness: 2017-02-16 17:16:40.036175Z] Running populate-webroot step. 09:16:40 INFO - Running pre-action listener: _resource_record_pre_action 09:16:40 INFO - Running main action method: populate_webroot 09:16:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:40 INFO - '--tppagecycles', 09:16:40 INFO - '7'], 09:16:40 INFO - 'tests': ['tsvgm']}}, 09:16:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:40 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:40 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['damp', 'tps']}, 09:16:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'tests': ['damp', 'tps']}, 09:16:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['dromaeo_dom']}, 09:16:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['basic_compositor_video']}, 09:16:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['a11yr', 09:16:40 INFO - 'ts_paint', 09:16:40 INFO - 'tpaint', 09:16:40 INFO - 'sessionrestore', 09:16:40 INFO - 'sessionrestore_no_auto_restore', 09:16:40 INFO - 'tabpaint']}, 09:16:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['tsvgx', 09:16:40 INFO - 'tsvgr_opacity', 09:16:40 INFO - 'tart', 09:16:40 INFO - 'tscrollx', 09:16:40 INFO - 'cart', 09:16:40 INFO - 'tsvg_static']}, 09:16:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:40 INFO - 'tsvgr_opacity', 09:16:40 INFO - 'tart', 09:16:40 INFO - 'tscrollx', 09:16:40 INFO - 'cart', 09:16:40 INFO - 'tsvg_static']}, 09:16:40 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'talos_options': ['--disable-e10s'], 09:16:40 INFO - 'tests': ['tp5o']}, 09:16:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:40 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'talos_options': ['--disable-e10s', 09:16:40 INFO - '--xperf_path', 09:16:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:40 INFO - 'tests': ['tp5n']}, 09:16:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:40 INFO - 'talos_options': ['--xperf_path', 09:16:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:40 INFO - 'tests': ['tp5n']}}, 09:16:40 INFO - 'talos.zip': {'path': '', 09:16:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:40 INFO - Running post-action listener: _resource_record_post_action 09:16:40 INFO - [mozharness: 2017-02-16 17:16:40.054461Z] Finished populate-webroot step (success) 09:16:40 INFO - [mozharness: 2017-02-16 17:16:40.054592Z] Running create-virtualenv step. 09:16:40 INFO - Running pre-action listener: _resource_record_pre_action 09:16:40 INFO - Running main action method: create_virtualenv 09:16:40 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:40 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:16:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:40 INFO - Using real prefix '/tools/python27' 09:16:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:43 INFO - Installing pip.................done. 09:16:43 INFO - Return code: 0 09:16:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:43 INFO - Reading from file tmpfile_stdout 09:16:43 INFO - Output received: 09:16:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:16:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:43 INFO - 'HOME': '/Users/cltbld', 09:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:43 INFO - 'LOGNAME': 'cltbld', 09:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:43 INFO - 'MOZ_NO_REMOTE': '1', 09:16:43 INFO - 'NO_EM_RESTART': '1', 09:16:43 INFO - 'PAGER': '/bin/cat', 09:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:43 INFO - 'PWD': '/builds/slave/test', 09:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:43 INFO - 'SHELL': '/bin/bash', 09:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:43 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:43 INFO - 'USER': 'cltbld', 09:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:44 INFO - 'XPC_FLAGS': '0x0', 09:16:44 INFO - 'XPC_SERVICE_NAME': '0', 09:16:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:16:44 INFO - Cleaning up... 09:16:44 INFO - Return code: 0 09:16:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:16:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:16:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:44 INFO - 'HOME': '/Users/cltbld', 09:16:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:44 INFO - 'LOGNAME': 'cltbld', 09:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:44 INFO - 'MOZ_NO_REMOTE': '1', 09:16:44 INFO - 'NO_EM_RESTART': '1', 09:16:44 INFO - 'PAGER': '/bin/cat', 09:16:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:44 INFO - 'PWD': '/builds/slave/test', 09:16:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:44 INFO - 'SHELL': '/bin/bash', 09:16:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:44 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:44 INFO - 'USER': 'cltbld', 09:16:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:44 INFO - 'XPC_FLAGS': '0x0', 09:16:44 INFO - 'XPC_SERVICE_NAME': '0', 09:16:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:44 INFO - Downloading/unpacking psutil>=3.1.1 09:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:48 INFO - Installing collected packages: psutil 09:16:48 INFO - Running setup.py install for psutil 09:16:48 INFO - building 'psutil._psutil_osx' extension 09:16:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:16:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:16:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:16:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:16:48 INFO - building 'psutil._psutil_posix' extension 09:16:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:16:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:48 INFO - ^ 09:16:48 INFO - 1 warning generated. 09:16:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:16:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:48 INFO - Successfully installed psutil 09:16:48 INFO - Cleaning up... 09:16:48 INFO - Return code: 0 09:16:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:48 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:48 INFO - 'HOME': '/Users/cltbld', 09:16:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:48 INFO - 'LOGNAME': 'cltbld', 09:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:48 INFO - 'MOZ_NO_REMOTE': '1', 09:16:48 INFO - 'NO_EM_RESTART': '1', 09:16:48 INFO - 'PAGER': '/bin/cat', 09:16:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:48 INFO - 'PWD': '/builds/slave/test', 09:16:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:48 INFO - 'SHELL': '/bin/bash', 09:16:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:48 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:48 INFO - 'USER': 'cltbld', 09:16:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:48 INFO - 'XPC_FLAGS': '0x0', 09:16:48 INFO - 'XPC_SERVICE_NAME': '0', 09:16:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:51 INFO - Installing collected packages: mozsystemmonitor 09:16:51 INFO - Running setup.py install for mozsystemmonitor 09:16:52 INFO - Successfully installed mozsystemmonitor 09:16:52 INFO - Cleaning up... 09:16:52 INFO - Return code: 0 09:16:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:52 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:52 INFO - 'HOME': '/Users/cltbld', 09:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:52 INFO - 'LOGNAME': 'cltbld', 09:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:52 INFO - 'MOZ_NO_REMOTE': '1', 09:16:52 INFO - 'NO_EM_RESTART': '1', 09:16:52 INFO - 'PAGER': '/bin/cat', 09:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:52 INFO - 'PWD': '/builds/slave/test', 09:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:52 INFO - 'SHELL': '/bin/bash', 09:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:52 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:52 INFO - 'USER': 'cltbld', 09:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:52 INFO - 'XPC_FLAGS': '0x0', 09:16:52 INFO - 'XPC_SERVICE_NAME': '0', 09:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:52 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:55 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:55 INFO - warning: no files found matching '*.txt' 09:16:55 INFO - no previously-included directories found matching 'build' 09:16:55 INFO - no previously-included directories found matching 'dist' 09:16:55 INFO - no previously-included directories found matching '.git*' 09:16:55 INFO - Installing collected packages: jsonschema, functools32 09:16:55 INFO - Running setup.py install for functools32 09:16:55 INFO - warning: no files found matching '*.txt' 09:16:55 INFO - no previously-included directories found matching 'build' 09:16:55 INFO - no previously-included directories found matching 'dist' 09:16:55 INFO - no previously-included directories found matching '.git*' 09:16:55 INFO - Successfully installed jsonschema functools32 09:16:55 INFO - Cleaning up... 09:16:55 INFO - Return code: 0 09:16:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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', '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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16: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 functools32==3.2.3-2 09:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:55 INFO - 'HOME': '/Users/cltbld', 09:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:55 INFO - 'LOGNAME': 'cltbld', 09:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:55 INFO - 'MOZ_NO_REMOTE': '1', 09:16:55 INFO - 'NO_EM_RESTART': '1', 09:16:55 INFO - 'PAGER': '/bin/cat', 09:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:55 INFO - 'PWD': '/builds/slave/test', 09:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:55 INFO - 'SHELL': '/bin/bash', 09:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:55 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:55 INFO - 'USER': 'cltbld', 09:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:55 INFO - 'XPC_FLAGS': '0x0', 09:16:55 INFO - 'XPC_SERVICE_NAME': '0', 09:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:56 INFO - Cleaning up... 09:16:56 INFO - Return code: 0 09:16:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:56 INFO - 'HOME': '/Users/cltbld', 09:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:56 INFO - 'LOGNAME': 'cltbld', 09:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:56 INFO - 'MOZ_NO_REMOTE': '1', 09:16:56 INFO - 'NO_EM_RESTART': '1', 09:16:56 INFO - 'PAGER': '/bin/cat', 09:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:56 INFO - 'PWD': '/builds/slave/test', 09:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:56 INFO - 'SHELL': '/bin/bash', 09:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:16:56 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:56 INFO - 'USER': 'cltbld', 09:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:56 INFO - 'XPC_FLAGS': '0x0', 09:16:56 INFO - 'XPC_SERVICE_NAME': '0', 09:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:00 INFO - Downloading docopt-0.6.1.tar.gz 09:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:00 INFO - Running setup.py install for blobuploader 09:17:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:00 INFO - Running setup.py install for requests 09:17:00 INFO - Running setup.py install for docopt 09:17:01 INFO - Successfully installed blobuploader requests docopt 09:17:01 INFO - Cleaning up... 09:17:01 INFO - Return code: 0 09:17:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:01 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:01 INFO - 'HOME': '/Users/cltbld', 09:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:01 INFO - 'LOGNAME': 'cltbld', 09:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:01 INFO - 'MOZ_NO_REMOTE': '1', 09:17:01 INFO - 'NO_EM_RESTART': '1', 09:17:01 INFO - 'PAGER': '/bin/cat', 09:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:01 INFO - 'PWD': '/builds/slave/test', 09:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:01 INFO - 'SHELL': '/bin/bash', 09:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:17:01 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:01 INFO - 'USER': 'cltbld', 09:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:01 INFO - 'XPC_FLAGS': '0x0', 09:17:01 INFO - 'XPC_SERVICE_NAME': '0', 09:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-bBfKGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-q4Ehy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-zl0DTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-v91WvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-mMvfYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:01 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-xcI4c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-z7RUq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-6P5lRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-Ymhb5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-zSqnov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-50h2xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-pa_nFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-vSsGJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-yKFb1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:02 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-bHS9nT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:03 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-mzHHmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:03 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-CR1Hrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:03 INFO - Running setup.py install for manifestparser 09:17:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:03 INFO - Running setup.py install for mozcrash 09:17:03 INFO - Running setup.py install for mozdebug 09:17:03 INFO - Running setup.py install for mozdevice 09:17:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:03 INFO - Running setup.py install for mozfile 09:17:03 INFO - Running setup.py install for mozhttpd 09:17:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozinfo 09:17:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozInstall 09:17:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozleak 09:17:04 INFO - Running setup.py install for mozlog 09:17:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for moznetwork 09:17:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:04 INFO - Running setup.py install for mozprocess 09:17:05 INFO - Running setup.py install for mozprofile 09:17:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozrunner 09:17:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Running setup.py install for mozscreenshot 09:17:05 INFO - Running setup.py install for moztest 09:17:05 INFO - Running setup.py install for mozversion 09:17:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:05 INFO - Cleaning up... 09:17:05 INFO - Return code: 0 09:17:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:05 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:05 INFO - 'HOME': '/Users/cltbld', 09:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:05 INFO - 'LOGNAME': 'cltbld', 09:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:05 INFO - 'MOZ_NO_REMOTE': '1', 09:17:05 INFO - 'NO_EM_RESTART': '1', 09:17:05 INFO - 'PAGER': '/bin/cat', 09:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:05 INFO - 'PWD': '/builds/slave/test', 09:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:05 INFO - 'SHELL': '/bin/bash', 09:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:17:05 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:05 INFO - 'USER': 'cltbld', 09:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:05 INFO - 'XPC_FLAGS': '0x0', 09:17:05 INFO - 'XPC_SERVICE_NAME': '0', 09:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-uJKoyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-mFE5tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-fbjLtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-l1L19S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-AFTuWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ChuFmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ZmCAfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-hcowmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:06 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-M_dRYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-ZBJR9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-s4UU1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-uaRH_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-xmReos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-PTcIIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-gvuvhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-SGqPbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:07 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-If44HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:17:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:10 INFO - Downloading blessings-1.6.tar.gz 09:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:10 INFO - Installing collected packages: blessings 09:17:10 INFO - Running setup.py install for blessings 09:17:10 INFO - Successfully installed blessings 09:17:10 INFO - Cleaning up... 09:17:10 INFO - Return code: 0 09:17:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:11 INFO - Reading from file tmpfile_stdout 09:17:11 INFO - Current package versions: 09:17:11 INFO - blessings == 1.6 09:17:11 INFO - blobuploader == 1.2.4 09:17:11 INFO - docopt == 0.6.1 09:17:11 INFO - functools32 == 3.2.3-2 09:17:11 INFO - jsonschema == 2.5.1 09:17:11 INFO - manifestparser == 1.1 09:17:11 INFO - mozInstall == 1.12 09:17:11 INFO - mozcrash == 1.0 09:17:11 INFO - mozdebug == 0.1 09:17:11 INFO - mozdevice == 0.49 09:17:11 INFO - mozfile == 1.2 09:17:11 INFO - mozhttpd == 0.7 09:17:11 INFO - mozinfo == 0.9 09:17:11 INFO - mozleak == 0.1 09:17:11 INFO - mozlog == 3.4 09:17:11 INFO - moznetwork == 0.27 09:17:11 INFO - mozprocess == 0.24 09:17:11 INFO - mozprofile == 0.28 09:17:11 INFO - mozrunner == 6.13 09:17:11 INFO - mozscreenshot == 0.1 09:17:11 INFO - mozsystemmonitor == 0.3 09:17:11 INFO - moztest == 0.8 09:17:11 INFO - mozversion == 1.4 09:17:11 INFO - psutil == 3.1.1 09:17:11 INFO - requests == 1.2.3 09:17:11 INFO - wsgiref == 0.1.2 09:17:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:11 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:11 INFO - 'HOME': '/Users/cltbld', 09:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:11 INFO - 'LOGNAME': 'cltbld', 09:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:11 INFO - 'MOZ_NO_REMOTE': '1', 09:17:11 INFO - 'NO_EM_RESTART': '1', 09:17:11 INFO - 'PAGER': '/bin/cat', 09:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:11 INFO - 'PWD': '/builds/slave/test', 09:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:11 INFO - 'SHELL': '/bin/bash', 09:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:17:11 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:11 INFO - 'USER': 'cltbld', 09:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:11 INFO - 'XPC_FLAGS': '0x0', 09:17:11 INFO - 'XPC_SERVICE_NAME': '0', 09:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:17:11 INFO - Cleaning up... 09:17:11 INFO - Return code: 0 09:17:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:11 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 0x10456fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104572768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb19d72c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, '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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:11 INFO - 'HOME': '/Users/cltbld', 09:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:11 INFO - 'LOGNAME': 'cltbld', 09:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:11 INFO - 'MOZ_NO_REMOTE': '1', 09:17:11 INFO - 'NO_EM_RESTART': '1', 09:17:11 INFO - 'PAGER': '/bin/cat', 09:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:11 INFO - 'PWD': '/builds/slave/test', 09:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:11 INFO - 'SHELL': '/bin/bash', 09:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:17:11 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:11 INFO - 'USER': 'cltbld', 09:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:11 INFO - 'XPC_FLAGS': '0x0', 09:17:11 INFO - 'XPC_SERVICE_NAME': '0', 09:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:11 INFO - Cleaning up... 09:17:11 INFO - Return code: 0 09:17:11 INFO - Running post-action listener: _resource_record_post_action 09:17:11 INFO - Running post-action listener: _start_resource_monitoring 09:17:11 INFO - Starting resource monitoring. 09:17:11 INFO - [mozharness: 2017-02-16 17:17:11.686795Z] Finished create-virtualenv step (success) 09:17:11 INFO - [mozharness: 2017-02-16 17:17:11.687759Z] Running install step. 09:17:11 INFO - Running pre-action listener: _resource_record_pre_action 09:17:11 INFO - Running main action method: install 09:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:11 INFO - Reading from file tmpfile_stdout 09:17:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:12 INFO - Reading from file tmpfile_stdout 09:17:12 INFO - Output received: 09:17:12 INFO - Usage: mozinstall [options] installer 09:17:12 INFO - Options: 09:17:12 INFO - -h, --help show this help message and exit 09:17:12 INFO - -d DEST, --destination=DEST 09:17:12 INFO - Directory to install application into. [default: 09:17:12 INFO - "/builds/slave/test"] 09:17:12 INFO - --app=APP Application being installed. [default: firefox] 09:17:12 INFO - mkdir: /builds/slave/test/build/application 09:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:17:32 INFO - Reading from file tmpfile_stdout 09:17:32 INFO - Output received: 09:17:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:17:32 INFO - Running post-action listener: _resource_record_post_action 09:17:32 INFO - [mozharness: 2017-02-16 17:17:32.109688Z] Finished install step (success) 09:17:32 INFO - [mozharness: 2017-02-16 17:17:32.109825Z] Running run-tests step. 09:17:32 INFO - Running pre-action listener: _resource_record_pre_action 09:17:32 INFO - Running main action method: run_tests 09:17:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:32 INFO - 2017-02-16 09:17:32.124 screenresolution[1906:12214] starting screenresolution argv=screenresolution get 09:17:32 INFO - 2017-02-16 09:17:32.127 screenresolution[1906:12214] Display 0: 1600x1200x32@60 09:17:32 INFO - 2017-02-16 09:17:32.132 screenresolution[1907:12217] starting screenresolution argv=screenresolution list 09:17:32 INFO - Available Modes on Display 0 09:17:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:32 INFO - Intel Iris: 09:17:32 INFO - Chipset Model: Intel Iris 09:17:32 INFO - Type: GPU 09:17:32 INFO - Bus: Built-In 09:17:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:32 INFO - Vendor: Intel (0x8086) 09:17:32 INFO - Device ID: 0x0a2e 09:17:32 INFO - Revision ID: 0x0009 09:17:32 INFO - Displays: 09:17:32 INFO - Display: 09:17:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:32 INFO - Display Serial Number: 1600x1200 60 09:17:32 INFO - Main Display: Yes 09:17:32 INFO - Mirror: Off 09:17:32 INFO - Online: Yes 09:17:32 INFO - Rotation: Supported 09:17:32 INFO - Return code: 0 09:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:32 INFO - Python 2.7.3 09:17:32 INFO - Return code: 0 09:17:32 INFO - grabbing minidump binary from tooltool 09:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:32 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 0x10434dd30>, '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 0x104274db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104342be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:17:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:17:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:17:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:32 INFO - Return code: 0 09:17:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0341 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZcmVrDSBTJ/Render', 09:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:32 INFO - 'HOME': '/Users/cltbld', 09:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:32 INFO - 'LOGNAME': 'cltbld', 09:17:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:32 INFO - 'MOZ_NO_REMOTE': '1', 09:17:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:32 INFO - 'NO_EM_RESTART': '1', 09:17:32 INFO - 'PAGER': '/bin/cat', 09:17:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:32 INFO - 'PWD': '/builds/slave/test', 09:17:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:32 INFO - 'SHELL': '/bin/bash', 09:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T12XLC9h2x/Listeners', 09:17:32 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 09:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:32 INFO - 'USER': 'cltbld', 09:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:32 INFO - 'XPC_FLAGS': '0x0', 09:17:32 INFO - 'XPC_SERVICE_NAME': '0', 09:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:17:32 INFO - mozversion application_buildid: 20170216073239 09:17:32 INFO - mozversion application_changeset: 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 09:17:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:17:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:32 INFO - mozversion application_name: Firefox 09:17:32 INFO - mozversion application_remotingname: firefox-dev 09:17:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:17:32 INFO - mozversion application_vendor: Mozilla 09:17:32 INFO - mozversion application_version: 53.0a2 09:17:32 INFO - mozversion platform_buildid: 20170216073239 09:17:32 INFO - mozversion platform_changeset: 3afbdf81cbf92638d71dfe6b159ed2de69cdda62 09:17:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:17:32 INFO - mozversion platform_version: 53.0a2 09:17:32 INFO - using testdate: 1487265452 09:17:32 INFO - actual date: 1487265452 09:17:32 INFO - starting webserver on 'localhost:49493' 09:17:32 INFO - SUITE-START | Running 2 tests 09:17:32 INFO - TEST-START | dromaeo_css 09:17:32 INFO - operating with platform_type : mac_ 09:17:32 INFO - Initialising browser for dromaeo_css test... 09:17:32 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpqXiLtC/profile http://localhost:49493/getInfo.html) 09:17:35 INFO - PROCESS | 1917 | 2017-02-16 09:17:35.556 plugin-container[1919:12392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:17:35 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:35 INFO - PROCESS | 1917 | 2017-02-16 09:17:35.559 plugin-container[1919:12392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c23, name = 'com.apple.CFPasteboardClient' 09:17:35 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:35 INFO - PROCESS | 1917 | 2017-02-16 09:17:35.559 plugin-container[1919:12392] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:35 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 09:17:35 INFO - PROCESS | 1917 | colorDepth:24 09:17:35 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 09:17:35 INFO - PROCESS | 1917 | __metrics 09:17:35 ERROR - PROCESS | 1917 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:17:36 INFO - TEST-INFO | 1917: exit 0 09:17:36 INFO - Browser initialized. 09:17:36 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:36 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpqXiLtC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:39 INFO - PROCESS | 1920 | 2017-02-16 09:17:39.056 plugin-container[1921:12517] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:17:39 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:39 INFO - PROCESS | 1920 | 2017-02-16 09:17:39.057 plugin-container[1921:12517] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f27, name = 'com.apple.CFPasteboardClient' 09:17:39 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:39 INFO - PROCESS | 1920 | 2017-02-16 09:17:39.057 plugin-container[1921:12517] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:48 INFO - PROCESS | 1920 | RSS: Main: 218890240 09:17:48 INFO - PROCESS | 1920 | 09:20:51 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49493/tests/dromaeo/cssquery-ext.html) 09:20:53 INFO - PROCESS | 1920 | RSS: Main: 240201728 09:20:53 INFO - PROCESS | 1920 | 09:22:36 INFO - PROCESS | 1920 | ************************* 09:22:36 INFO - PROCESS | 1920 | A coding exception was thrown and uncaught in a Task. 09:22:36 INFO - PROCESS | 1920 | 09:22:36 ERROR - PROCESS | 1920 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:36 INFO - PROCESS | 1920 | Full stack: 09:22:36 INFO - PROCESS | 1920 | ************************* 09:23:56 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-ext.html (next: http://localhost:49493/tests/dromaeo/cssquery-jquery.html) 09:23:56 INFO - PROCESS | 1920 | RSS: Main: 246792192 09:23:56 INFO - PROCESS | 1920 | 09:26:58 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49493/tests/dromaeo/cssquery-mootools.html) 09:26:59 INFO - PROCESS | 1920 | RSS: Main: 253280256 09:26:59 INFO - PROCESS | 1920 | 09:30:01 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49493/tests/dromaeo/cssquery-prototype.html) 09:30:02 INFO - PROCESS | 1920 | RSS: Main: 250277888 09:30:02 INFO - PROCESS | 1920 | 09:33:04 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49493/tests/dromaeo/cssquery-yui.html) 09:33:05 INFO - PROCESS | 1920 | RSS: Main: 255152128 09:33:05 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-yui.html (next: http://localhost:49493/tests/dromaeo/cssquery-dojo.html) 09:36:08 INFO - PROCESS | 1920 | RSS: Main: 256831488 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | __start_tp_report 09:36:08 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 09:36:08 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 09:36:08 INFO - PROCESS | 1920 | |i|pagename|runs| 09:36:08 INFO - PROCESS | 1920 | |0;dojo.html;3334.416477116504;3355.2953879685265;3374;3392.287619599884;3415.8804441844527;5707.343655479621;5745.396733342996;5797.507211886984;5808.82573522794;5816.825495235144;5763.366029736736;5803.187553742479;5811.680357580342;5837.649741015532;5838.678872662002;5195.142801437768;5287.814926477574;5292.603054770908;5328.0675881720545;5347.1177255752755;4072.490938632671;4268.338407546835;5221.138512145491;5366.785328586871;5398.78404863805;5148.279240906276;5188.533032027136;5220.138677118271;5246.291750613656;5258.342707161605;4268.551802060801;5265.605079619044;5276.498732620395;5322.547583455411;5348.893022139574;5134.537891589737;5186.299849520305;5226.5818734501145;5262.23697563855;5292.92060619091;4265.637420819234;5474;5704.429557044304;5784.768609255625;5815.621984571032;6054.666993315366;6161.815145545641;6243.313235544087;6276.184096067482;6285.245770507523;5136.717480538568;6275.278342990523;6288.962321217039;6310.211223597051;6329;5921.703914804242;5947.910781338283;5949.9107513387335;5967.373425790316;5981.102834574762;6161.568690191733;6208.161898143783;6252.6873656317;6254.781082662085;6310.4636105930595;6189.6286222826775;6201.379862013853;6214.906776398358;6229.252489701174;6229.314775374705;6283;6285.685715714196;6290.21372328459;6291.213598300213;6312.810615681537;6137.202163718777;6143.078538219303;6168.568200226031;6172.8765424691255;6182.752689892444;6235.095911092866;6243.469305109116;6248.343923888017;6274.874502510015;6278.246610406781;4967.081089998362;4986.750662466935;4991.151504244287;5000.699958002531;5003.849884503471;4364.934525982113;4423.336499525097;4463.12968971054;4500.594946454835;4514.45826500822;5293.364796224478;5861.677607731531;5937.881242375215;5952.077427998667;5984.640921544721;5737.799177028784;6271.874562508724;6283;6297.96851015742;6321.241451025814;5710;5787.102999035156;5791.0734282514595;5827.271591051119;5870.501007414332;5515.669059856422;5533.806316778974;5543.1408131739645;5547.389787123413;5576.749046292926;6426.325235850169;6432;6443.130177425987;6456.741730330734;6473.320301368289;6133.273457575459;6201.441870231701;6202.162708034538;6230.657313847782;6282.43458088756;6321.936780632319;6346.619202847844;6358.1734374531015;6443.581167224115;6445.387688169616;6206.875862482815;6224.751009959551;6236.906446403217;6245.188125543832;6269.373062693694;5077.7715002824225;6200.906986395298;6215.689215539295;6253.155823963889;6267.059941008885;5280.60395470349;6085.908711369245;6109.586862300561;6129.542179248731;6156.138140660221;6224.1597384352535;6225.159603453476;6232.439080482597;6241.344658810759;6255.093011513396;6021.789237376671;6072.05883088122;6129.172561704291;6139.171211886517;6159.692015399301;5746.597738158289;6050.90923636137;6108.251008765869;6126.724297406538;6142.928535732134;4708.623310135249;6105.114758360101;6105.6947152641305;6123.479504242189;6133.53998450127 09:36:08 INFO - PROCESS | 1920 | |1;ext.html;490.8159440209919;492.8176574667374;495.6505663507228;505.6157320436468;514.6628958032487;39507.60492395075;42208.577914220885;42386.99999999996;42438;42556.78721606395;43107.78446107765;43986.78006609962;44094.33858492125;44111;44120.55879441205;8551.273141782956;9153.67962121326;9507.952460237724;9512.09635084666;9521;8534.487930724146;11325.490352934137;11558.306501609888;11560.190786644938;11764.647060588197;11665.533378664843;11693.649190524298;11749.823752643717;11764.058875289957;11768.646940591796;11574.6527604172;11596.652100436959;11652.533898644044;11656.825147622792;11690.766184676258;11431.314121152758;11450.828237576443;11503.309801411859;11520.654380368602;11524.366159861203;41131.38302925458;41287;41464.37803432951;41495;41552.37671434931;3931.4495970564144;3966.3257246268204;3966.458405126143;4004.3192657248337;4013.819378127991;29997;30276.54585181224;30967.845160774275;32027;32054;3215.083701145162;3226.6934641209045;3234.644189139193;3253.9511907321407;3266.1344743642953;489017;494660;495312;496567;504772;67677;68055.65972170107;68143;68164.97752533716;68294;70594.64702676453;71339.28660713327;71346.28653713396;71850.28149718541;71882.64058679673;16402.753958690628;16881.987080775194;16896.746548801777;16913.7462938056;16995.660086798194;11598.478068486938;11939.46272417726;12547.686307842194;12931.935340323427;13010.869891301156;18330.816691833177;18576;18705;18823.34118305853;19007.429777106434;5884.911291411103;6548.345165483413;6687.264400915896;6796.660166991631;6816.829579260559;712.0245263988369;876.8640860666607;888.3781353052889;911.5578944212052;917.8256131335025;29509;30549.38901221963;30604.69395306063;31002.379952400825;31232.219194519865;836.5984327522701;1074.9247552671395;1092.7213560542011;1100.1254003067581;1180.4805885410392;14083.85916140846;15249.847501524842;15665.530034098996;15985.44050958211;16050.277737501841;591608;654118;671781;672959;847617;42259.7887010563;54914.72542637261;55350.44649553533;55361;55539.444605554236;1285.5372066056211;1296.3388671777461;1403.4175817035816;1413.3216056293038;1433.5770947570493;26226.688665566264;26984.865075674497;29355.11934641964;29501.85249073741;30210.84894575513;48341.5165848337;48511.75744121257;48846;48848.267275990176;48939.265911010654;433487;450398.74800627097;516308;516740;517259;22464.88767556217;25705.742942570334;25836.483270334113;26530.86734566392;26579.60130598004;42717.57282427136;43290.56709432865;44605.77697111494;44877;44914.55085449103;30655.2336191597;31137.221569460038;34757.9572612822;35105.473417899266;37087.6291237095;32091.839540802146;33953.49069764005;34419.483707744905;36730.63269367379;37009.07477313089;34649.96050118501;35364.115897102776;36129.096772580895;36159.27681446304;36315.72894948664;30391.240218994702;30496.390072197988;32191;32430.35139297248;33165;24104.638430423907;24220.39449013789;24374.634380483953;24471.265862024167;24576.385590360384 09:36:08 INFO - PROCESS | 1920 | |2;jquery.html;343.98108104054273;374.1898789121552;375.0118437916092;377.51866370377763;402.259841890921;11586.710332241688;12066.095042871786;12153.696157596065;12188.085893557974;19606.803931960676;22298;22733.31800045997;23289;23412.180573679925;23810.404739881513;5599.992001439739;6483.092367068614;6751.257361690235;6761.357671021246;6776.559523630973;6610.867782644368;6717.529772915897;6763.154605674291;6773.695183716744;6783.219929708072;5918.644881307136;6207.844803879917;6622.602643841385;6756.223034351064;6775.559588626748;6502.674866256668;6531.443808610735;6583.736650533949;6588.143541339643;6589.835254118638;5662.235598194274;6481.319461456526;6699.464042876558;6708.698108585126;6717.328267173243;22267.44331391712;28917.421651566852;29245.853770731224;29396.971106011188;29500.11499655014;2891.956620650692;2926.4732348177317;2942.955855662167;2946.8379239141836;2954.7193016663596;6102.267727872683;10342.7414314642;16509.996422751294;17150.485485435456;17722.822771772248;1892.5552495163627;1947.8928658923753;1953.4237399967044;1956.3055115433958;1972.891490967996;111938.88061119366;116080.8391916087;136867.31566342205;157845.21077394654;161143;24383.634245486333;24735.010599576177;24887.50224995526;24910.252692419257;25081.87459062693;42979.57020429818;43023.35464968028;43060.35409468798;44057.55942440599;44063.55936440659;8549.871751923725;8812.603432845537;9010.053944335881;9073.092690730875;9156.908430915739;8650.74047778555;8713.085126061798;8722.433041852386;8844;8886.73339799807;4790.353302304214;4899.1426500363;4904.068227036852;4932.432770231398;4982.875428114254;1752.4567384110967;2029.9086541105685;2054.815066644009;2074.8236399905877;2083.4895450614695;928.6146249306597;968.3512046928574;989.42118860466;997.1623835977668;1038.1746511523356;21665.025073871715;21684.855695924;21892.89053554722;21978;22260.777392226195;1009.1170226052204;1019.37308555238;1024.262530977695;1049.816282150636;1055.2929537209934;11146.554137834395;11375.26060806045;11423.942880285711;11451;11541.826872596917;432425;682644;702002;738433;738717;124976.75023249652;148060.25969870083;148179;156594.21702891414;159767.40232597524;996.6163027234422;1011.6408674920298;1023.2070145637191;1030.6753372687579;1030.7577719235974;19209.90395048072;20422.945094742263;20601.07295171751;20683.896580517;20694.275700350416;60627.69686151717;62188;62370.376296238275;62569.68715156395;64532.677336613015;86480.27039459301;88467.67298490656;89840.5507972456;90166.54916725375;90763.54618226866;8122.878156827771;8170.83658326842;8252.876206857021;8391.076981531905;8417.87373189415;10601.41692206936;12206.938965305117;12381.380930953597;12489.250644961332;12631.621051368473;10039.49802509857;10354.585816567253;10395;10456.63401780934;10947.124230061736;10690.946545267223;10814.729631759268;10820.837687434538;11004.724881878017;11166.776664466503;10609.840852387375;10821.729456763329;10890;11160.832587511031;11206.103511719206;6323.936760632519;7107.786766397016;7722.227777222458;8348.33213342919;8429.4942303462;7712.344450721527;9373.1564159226;10521.421321827373;10950.780984380428;11335.603253886075 09:36:08 INFO - PROCESS | 1920 | |3;mootools.html;1260.2942352282719;1261.6845788552855;1286.5239861251337;1427.6288165077076;1431.1270125223612;7176;7618.657160427781;7896.763097107081;8054.556999365032;8593.871091933619;7383.261673832614;8003.91996080039;8016.516944365297;8365.498070115797;8399.328053755686;1957.4323446200585;2292.2321022457454;2347.1667558016916;2353.9058437662475;2356.3166681662296;2069.1102825784906;2160.9891950540305;2326.6626339180807;2375.192434572245;2381.9638457271058;2267.6938613287243;2349.400902769791;2349.906003759856;2355.776201260886;2357.7170739511284;2332.7084114485688;2339.3800642829665;2351.318117745852;2354.693889794333;2358.9882050589636;1989.641864464396;2332.650102484624;2375.4298968247667;2381.404648837791;2387.6179811230118;8472.576371181478;8495.532745698983;8537.317014638815;8544.914550854473;8548.957255213747;4928.827491037797;5067.163917953533;5067.721275329855;5073.594112470993;5086.567641750456;3257.348530293927;3461.238527523941;3514.6309637487952;3621.7102631789394;3623.384024715778;1054.4464156317918;1183.1717797541755;1193.6717402714237;1251.3242848861603;1256.7298030923396;10708.19688523364;10798.298110622783;10939.398333091676;10948.945255273673;10971.945140274327;4707.835225767081;4724.141894154056;4743.573078422958;4800.783964721596;4838.080764654705;9626.9037309626;9704.902950970541;9732.90267097334;9756.560954757053;9757.951210243904;5254.868628284324;5357.196420536873;5359.249705041298;5364.383095943938;5381.623286369995;4591.586757191802;4782.234842425195;4830.734309613005;4862.294967229732;4891.1685013547785;3740.1958578905665;3934.016495876031;4000.7999600019884;4086.162336720965;4107.691923105779;1155.930644161353;1179.7463545337794;1222.9816552751715;1237.5049980007889;1289.4777615065914;861.8664107063415;873.3406278259873;875.7372788163525;892.9553522323857;895.1809094678297;4841.757912104381;5149.948500514947;5370.597205209547;5505.697186654692;5506.449355064542;941.9952900235455;984.158544444504;989.2728844299434;998.6854140945724;998.8962196772462;3221;3231.1275955492356;3486.9825650871585;3649.3248748981523;3665.9633403666157;9865.309428339948;10160.949195254125;10161.745956351151;10184.694459166238;10215.7446063849;9483.76290592727;9860.65487707941;9937.950310248403;10043.19654427644;10126.493675316206;7191.712331506786;7375.008747638215;7626.504277221963;7895.802604934923;7915.525068495908;5017.67385119966;5372.167314066326;5436;5592.776288948559;5594.496495315441;8931.19619234272;9011.91856977167;9086;9324.067593240892;9369.016253293306;7870.9606451967375;9130.908690913006;9373;9483.66807161746;9500.572474238537;7166.605836679033;7268.200497945116;7268.382187513909;7311.4150867929375;7321.78034658961;7572.848543028998;7775;7833.843323133619;7868;7888.2506161914525;7074.71701131949;7120.215547983274;7122.893156602759;7190.712371505081;7259.274072592699;6973.919042548413;6997.265287144776;7001.754938577125;7038.2961703829205;7171.1394632644415;5335.866603334947;6344.936550634619;6347.206599175103;6355.5551111421755;6381.680915954276;4067.51189857219;4751.216049351819;4782.282657601388;4816.4220293564995;4886.902261954812;4825.9758701206265;4868.926966095441;4935.703857768545;5021.1213037717525;5052.216906379517 09:36:08 INFO - PROCESS | 1920 | |4;prototype.html;306.55396398240555;351.4429629037975;359.43209728628767;369.04601604851456;373.95886452490237;5215.426303106655;5308.283381743468;5344.679319240849;5396.763540620614;5532.917006244909;4531.909361812762;5159.993801208766;5283.735813209334;5327.893442131155;5456.127019676853;4232.76719780412;4240.300350442173;4253.51084625269;4265.914681706364;4269.648326064041;3908.218356328746;4116.279651061067;4176.665866730655;4178.8328466861285;4254.872353829374;3670.853165873362;4170.645495132917;4200.201961627296;4202.432671589327;4204.76873771944;3544.946825797615;3994.6018893387372;4189.371594260855;4205.7897105144775;4208.516020657633;3773.3019391412677;3776.150366167618;3776.4146557283666;3784.6215378462207;3788.753731007476;4285.114549598166;4591.242444996572;5074.797008119671;5163.896722065575;5255.106631872591;4462.776861156929;4859.246816743411;4888.486708895551;4891.5353041461;4913.459519452857;5434.212039254326;5469;5736.598438109334;5782.56630752691;5816.993137367602;3967.3057214987552;4012.469027255996;4100.34394496882;4130;4150.522689890671;4923.901521969577;4998.875028124289;5982.252218472691;6003.559145804976;6067;3781.4327850822046;3913.8434462621176;4232.0054787125;4240.1519696060295;4277.8288868444915;3559.964400356015;3561.468568515563;4074.3888416737727;4083.8774836754947;4288.378185163134;4352.347147927836;4585.784767036738;4660.39414876064;4865.12427762999;4890.706557606555;4917.606591472673;5782.479576838105;5795.507381872504;5835.708214589254;5842.678652674059;1185.8319555238083;1224.9142560020714;1246.8902676617818;1328.2429015461094;1384.847666756656;840.7561807076002;859.4971941414218;952.8618350339301;955.5556666150273;985.69936169469;4191.224623444673;5003.023805596842;5102.285680004802;5119.513646203604;5127.410347809975;4560.612347950461;5159.8132429540665;5178.870528236749;5345.1982202669915;5356.143017117242;4263.616274535307;4758.952410475851;4953.752312384367;5121.359830021247;5158.793648254103;2503.962440563393;2652.4827658606773;2964.2293003819163;2971.539411391238;2974.479466093425;4921.581665558396;5023;5225.712585807741;5823.88352232953;5847.26909136358;5549.114460480528;5636.239107720406;5768.9711551442815;5799.8250656004;5837.9124313135335;5103.647533403651;5206.401263854629;5555.2222688822785;5694.886102277931;5793.681347525927;4849.660523763303;5603.775848966159;5617.455199820082;5686.43135686428;5686.606781338599;4918.483559226373;5171.612129090408;5330.577687254052;5551.528120109836;5571.3871474137;4601.631869450369;4830.3237546742785;5369.946300536944;5584.972075139598;5702.629329093762;3696.9445458318683;3869.6323849234277;4013.5785742497787;4051.372037334218;4058.736182148151;4927.678107643807;5324;5587.329520457572;5587.563648356313;5599.524040456607;5536.944630553643;5598.86002849932;5660.15097735343;5697.487226149667;5742.449538624464;4878.926816097691;5707.914381284201;5717.942180696585;5723.7996670116345;5728.519300557584;5301.708406037705;5399.973000134974;5592.580556458363;5610.607257491936;5627.260194601566;5548.805791797267;5729.736753982237;5781.017227071324;5788.102844059177;5795.420457954171;5004.523665518608;5498.75573411673;5614.529606972919;5642.6614403135945;5709.658230315956 09:36:08 INFO - PROCESS | 1920 | |5;yui.html;696.1993707236674;700.1633048507036;754.5548126605302;757.9768154088968;846.7036537211975;14623.415063397464;15163.92418037911;15554.84445155548;15609.843901560995;15776;14165.575032749008;14311.21288329144;14356.569302920903;14506.854931450682;14531.782023269632;833.9791505212357;856.0626114404748;858.0989960541438;860.0582362313257;860.7546849147994;677.427573700225;700.0619170311782;825.8513467575834;852.8891244138254;853.5774791478227;896.6503063805121;900.3157600223811;901.0944001278706;902.0483390023522;903.0788595632451;881.0176653031888;884.884964954555;889.937704360695;890.5947793753891;890.9198172164504;661.2230629010893;665.6605131382981;666.1739443090532;666.5667316244432;669.581511555278;14933.402663893432;15189.316480758393;15384.15387153706;15461.613459663486;15566.610834729108;14446.988710790249;14773.556793296219;14834.109953402722;14859.034162779384;14921.850781492154;11461.828072578917;11755.764884702343;11815.527378904833;11861.644150675493;11896.04831613469;3585.121645196917;3615.6022837487853;3665.596784353719;3702.7593206441493;3830.5977872323283;111259.44370278259;111883;114358;115070.97251596238;116782;10616.416097114577;10714.839277410845;10735.731606709895;10741.570337186426;10775.946120269506;18410.447686569423;21330.57338853245;21578.46053848635;21630;21888.12447502114;6383.489320854321;7429.665665044978;7469.7385591504035;7557.886631700529;7650.923490765133;4113.753174809521;4189.2040512302565;4192.413062171298;4209.1792100540715;4221.64116050133;3030.65147508035;3550.396432606463;3572.8566858604995;3578.3916734155255;3618.620044895301;643.6009674002148;648.3516483516484;662.0499583098269;662.7672529095878;667.9732810687615;614.6926536731634;617.5090802811775;632;637.9936200638027;643.7360682120402;9422.29332800029;10517.474126293808;10916;10955.561777528968;11167.553297868157;536.8013834881119;565.2538648983302;573.7966209754562;621.201755743867;674.6289540752566;4674.579287864109;4946.629002824802;4947.90104197914;4958.801647934114;4960.50394960501;97626;99943;100173;100213;102330;68437.3156268441;71541.28458715347;73993;84136.57931710302;91753;56566;78055.60972195103;79371;80599.19400805917;82293.17706822975;7565;9017.50403727775;9444.858327125236;9458.054194580487;9464.384814987005;51143.74428127985;51565.968680625425;52148;52435.47564524306;52805.47194528006;61759.38240617716;65504.672476637315;65738.34261657322;66070;66972.33027669661;10419.479026048819;11618.0705543558;11919.225250358699;11975.64073077809;12087.75824483523;12502.874971250534;12696.17474864131;12859.678508037374;12940.935295323463;13639.113457625619;11345.829812552653;11481.598144064918;11986;12151.453184606891;12355.69110772202;11869.406529673655;11912.463939122934;11929.642110736691;12015.639530814089;12236.841452709601;11514.712132196762;13224.206547607175;13464.057515973787;13468.865311347154;13489.865101348862;10244.692659220234;10802.67591972242;11096;11209.663710088711;11253.1560132992;9863.063009014133;10038.146757525692;10137.341072830242;10935.56257749681;10959.452027398758 09:36:08 INFO - PROCESS | 1920 | __end_tp_report 09:36:08 INFO - PROCESS | 1920 | __start_cc_report 09:36:08 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,389 09:36:08 INFO - PROCESS | 1920 | __end_cc_report 09:36:08 INFO - PROCESS | 1920 | __startTimestamp1487266568537__endTimestamp 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | ------- Summary: start ------- 09:36:08 INFO - PROCESS | 1920 | Number of tests: 6 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#0] dojo.html Cycles:165 Average:5747.09 Median:6050.91 stddev:688.29 (11.4%) stddev-sans-first:663.87 09:36:08 INFO - PROCESS | 1920 | Values: 3334.4 3355.3 3374.0 3392.3 3415.9 5707.3 5745.4 5797.5 5808.8 5816.8 5763.4 5803.2 5811.7 5837.6 5838.7 5195.1 5287.8 5292.6 5328.1 5347.1 4072.5 4268.3 5221.1 5366.8 5398.8 5148.3 5188.5 5220.1 5246.3 5258.3 4268.6 5265.6 5276.5 5322.5 5348.9 5134.5 5186.3 5226.6 5262.2 5292.9 4265.6 5474.0 5704.4 5784.8 5815.6 6054.7 6161.8 6243.3 6276.2 6285.2 5136.7 6275.3 6289.0 6310.2 6329.0 5921.7 5947.9 5949.9 5967.4 5981.1 6161.6 6208.2 6252.7 6254.8 6310.5 6189.6 6201.4 6214.9 6229.3 6229.3 6283.0 6285.7 6290.2 6291.2 6312.8 6137.2 6143.1 6168.6 6172.9 6182.8 6235.1 6243.5 6248.3 6274.9 6278.2 4967.1 4986.8 4991.2 5000.7 5003.8 4364.9 4423.3 4463.1 4500.6 4514.5 5293.4 5861.7 5937.9 5952.1 5984.6 5737.8 6271.9 6283.0 6298.0 6321.2 5710.0 5787.1 5791.1 5827.3 5870.5 5515.7 5533.8 5543.1 5547.4 5576.7 6426.3 6432.0 6443.1 6456.7 6473.3 6133.3 6201.4 6202.2 6230.7 6282.4 6321.9 6346.6 6358.2 6443.6 6445.4 6206.9 6224.8 6236.9 6245.2 6269.4 5077.8 6200.9 6215.7 6253.2 6267.1 5280.6 6085.9 6109.6 6129.5 6156.1 6224.2 6225.2 6232.4 6241.3 6255.1 6021.8 6072.1 6129.2 6139.2 6159.7 5746.6 6050.9 6108.3 6126.7 6142.9 4708.6 6105.1 6105.7 6123.5 6133.5 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#1] ext.html Cycles:180 Average:69288.27 Median:35234.79 stddev:151741.13 (430.7%) stddev-sans-first:152078.89 09:36:08 INFO - PROCESS | 1920 | Values: 490.8 492.8 495.7 505.6 514.7 39507.6 42208.6 42387.0 42438.0 42556.8 43107.8 43986.8 44094.3 44111.0 44120.6 8551.3 9153.7 9508.0 9512.1 9521.0 8534.5 11325.5 11558.3 11560.2 11764.6 11665.5 11693.6 11749.8 11764.1 11768.6 11574.7 11596.7 11652.5 11656.8 11690.8 11431.3 11450.8 11503.3 11520.7 11524.4 41131.4 41287.0 41464.4 41495.0 41552.4 3931.4 3966.3 3966.5 4004.3 4013.8 29997.0 30276.5 30967.8 32027.0 32054.0 3215.1 3226.7 3234.6 3254.0 3266.1 489017.0 494660.0 495312.0 496567.0 504772.0 67677.0 68055.7 68143.0 68165.0 68294.0 70594.6 71339.3 71346.3 71850.3 71882.6 16402.8 16882.0 16896.7 16913.7 16995.7 11598.5 11939.5 12547.7 12931.9 13010.9 18330.8 18576.0 18705.0 18823.3 19007.4 5884.9 6548.3 6687.3 6796.7 6816.8 712.0 876.9 888.4 911.6 917.8 29509.0 30549.4 30604.7 31002.4 31232.2 836.6 1074.9 1092.7 1100.1 1180.5 14083.9 15249.8 15665.5 15985.4 16050.3 591608.0 654118.0 671781.0 672959.0 847617.0 42259.8 54914.7 55350.4 55361.0 55539.4 1285.5 1296.3 1403.4 1413.3 1433.6 26226.7 26984.9 29355.1 29501.9 30210.8 48341.5 48511.8 48846.0 48848.3 48939.3 433487.0 450398.7 516308.0 516740.0 517259.0 22464.9 25705.7 25836.5 26530.9 26579.6 42717.6 43290.6 44605.8 44877.0 44914.6 30655.2 31137.2 34758.0 35105.5 37087.6 32091.8 33953.5 34419.5 36730.6 37009.1 34650.0 35364.1 36129.1 36159.3 36315.7 30391.2 30496.4 32191.0 32430.4 33165.0 24104.6 24220.4 24374.6 24471.3 24576.4 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#2] jquery.html Cycles:180 Average:39732.16 Median:16086.16 stddev:112088.69 (696.8%) stddev-sans-first:112364.12 09:36:08 INFO - PROCESS | 1920 | Values: 344.0 374.2 375.0 377.5 402.3 11586.7 12066.1 12153.7 12188.1 19606.8 22298.0 22733.3 23289.0 23412.2 23810.4 5600.0 6483.1 6751.3 6761.4 6776.6 6610.9 6717.5 6763.2 6773.7 6783.2 5918.6 6207.8 6622.6 6756.2 6775.6 6502.7 6531.4 6583.7 6588.1 6589.8 5662.2 6481.3 6699.5 6708.7 6717.3 22267.4 28917.4 29245.9 29397.0 29500.1 2892.0 2926.5 2943.0 2946.8 2954.7 6102.3 10342.7 16510.0 17150.5 17722.8 1892.6 1947.9 1953.4 1956.3 1972.9 111938.9 116080.8 136867.3 157845.2 161143.0 24383.6 24735.0 24887.5 24910.3 25081.9 42979.6 43023.4 43060.4 44057.6 44063.6 8549.9 8812.6 9010.1 9073.1 9156.9 8650.7 8713.1 8722.4 8844.0 8886.7 4790.4 4899.1 4904.1 4932.4 4982.9 1752.5 2029.9 2054.8 2074.8 2083.5 928.6 968.4 989.4 997.2 1038.2 21665.0 21684.9 21892.9 21978.0 22260.8 1009.1 1019.4 1024.3 1049.8 1055.3 11146.6 11375.3 11423.9 11451.0 11541.8 432425.0 682644.0 702002.0 738433.0 738717.0 124976.8 148060.3 148179.0 156594.2 159767.4 996.6 1011.6 1023.2 1030.7 1030.8 19209.9 20422.9 20601.1 20683.9 20694.3 60627.7 62188.0 62370.4 62569.7 64532.7 86480.3 88467.7 89840.6 90166.5 90763.5 8122.9 8170.8 8252.9 8391.1 8417.9 10601.4 12206.9 12381.4 12489.3 12631.6 10039.5 10354.6 10395.0 10456.6 10947.1 10690.9 10814.7 10820.8 11004.7 11166.8 10609.8 10821.7 10890.0 11160.8 11206.1 6323.9 7107.8 7722.2 8348.3 8429.5 7712.3 9373.2 10521.4 10950.8 11335.6 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#3] mootools.html Cycles:180 Average:5402.17 Median:5070.66 stddev:2961.67 (58.4%) stddev-sans-first:2953.62 09:36:08 INFO - PROCESS | 1920 | Values: 1260.3 1261.7 1286.5 1427.6 1431.1 7176.0 7618.7 7896.8 8054.6 8593.9 7383.3 8003.9 8016.5 8365.5 8399.3 1957.4 2292.2 2347.2 2353.9 2356.3 2069.1 2161.0 2326.7 2375.2 2382.0 2267.7 2349.4 2349.9 2355.8 2357.7 2332.7 2339.4 2351.3 2354.7 2359.0 1989.6 2332.7 2375.4 2381.4 2387.6 8472.6 8495.5 8537.3 8544.9 8549.0 4928.8 5067.2 5067.7 5073.6 5086.6 3257.3 3461.2 3514.6 3621.7 3623.4 1054.4 1183.2 1193.7 1251.3 1256.7 10708.2 10798.3 10939.4 10948.9 10971.9 4707.8 4724.1 4743.6 4800.8 4838.1 9626.9 9704.9 9732.9 9756.6 9758.0 5254.9 5357.2 5359.2 5364.4 5381.6 4591.6 4782.2 4830.7 4862.3 4891.2 3740.2 3934.0 4000.8 4086.2 4107.7 1155.9 1179.7 1223.0 1237.5 1289.5 861.9 873.3 875.7 893.0 895.2 4841.8 5149.9 5370.6 5505.7 5506.4 942.0 984.2 989.3 998.7 998.9 3221.0 3231.1 3487.0 3649.3 3666.0 9865.3 10160.9 10161.7 10184.7 10215.7 9483.8 9860.7 9938.0 10043.2 10126.5 7191.7 7375.0 7626.5 7895.8 7915.5 5017.7 5372.2 5436.0 5592.8 5594.5 8931.2 9011.9 9086.0 9324.1 9369.0 7871.0 9130.9 9373.0 9483.7 9500.6 7166.6 7268.2 7268.4 7311.4 7321.8 7572.8 7775.0 7833.8 7868.0 7888.3 7074.7 7120.2 7122.9 7190.7 7259.3 6973.9 6997.3 7001.8 7038.3 7171.1 5335.9 6344.9 6347.2 6355.6 6381.7 4067.5 4751.2 4782.3 4816.4 4886.9 4826.0 4868.9 4935.7 5021.1 5052.2 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#4] prototype.html Cycles:180 Average:4560.34 Median:5048.90 stddev:1352.78 (26.8%) stddev-sans-first:1318.36 09:36:08 INFO - PROCESS | 1920 | Values: 306.6 351.4 359.4 369.0 374.0 5215.4 5308.3 5344.7 5396.8 5532.9 4531.9 5160.0 5283.7 5327.9 5456.1 4232.8 4240.3 4253.5 4265.9 4269.6 3908.2 4116.3 4176.7 4178.8 4254.9 3670.9 4170.6 4200.2 4202.4 4204.8 3544.9 3994.6 4189.4 4205.8 4208.5 3773.3 3776.2 3776.4 3784.6 3788.8 4285.1 4591.2 5074.8 5163.9 5255.1 4462.8 4859.2 4888.5 4891.5 4913.5 5434.2 5469.0 5736.6 5782.6 5817.0 3967.3 4012.5 4100.3 4130.0 4150.5 4923.9 4998.9 5982.3 6003.6 6067.0 3781.4 3913.8 4232.0 4240.2 4277.8 3560.0 3561.5 4074.4 4083.9 4288.4 4352.3 4585.8 4660.4 4865.1 4890.7 4917.6 5782.5 5795.5 5835.7 5842.7 1185.8 1224.9 1246.9 1328.2 1384.8 840.8 859.5 952.9 955.6 985.7 4191.2 5003.0 5102.3 5119.5 5127.4 4560.6 5159.8 5178.9 5345.2 5356.1 4263.6 4759.0 4953.8 5121.4 5158.8 2504.0 2652.5 2964.2 2971.5 2974.5 4921.6 5023.0 5225.7 5823.9 5847.3 5549.1 5636.2 5769.0 5799.8 5837.9 5103.6 5206.4 5555.2 5694.9 5793.7 4849.7 5603.8 5617.5 5686.4 5686.6 4918.5 5171.6 5330.6 5551.5 5571.4 4601.6 4830.3 5369.9 5585.0 5702.6 3696.9 3869.6 4013.6 4051.4 4058.7 4927.7 5324.0 5587.3 5587.6 5599.5 5536.9 5598.9 5660.2 5697.5 5742.4 4878.9 5707.9 5717.9 5723.8 5728.5 5301.7 5400.0 5592.6 5610.6 5627.3 5548.8 5729.7 5781.0 5788.1 5795.4 5004.5 5498.8 5614.5 5642.7 5709.7 09:36:08 INFO - PROCESS | 1920 | 09:36:08 INFO - PROCESS | 1920 | [#5] yui.html Cycles:180 Average:20008.60 Median:3972.18 stddev:29118.69 (733.1%) stddev-sans-first:29164.27 09:36:08 INFO - PROCESS | 1920 | Values: 696.2 700.2 754.6 758.0 846.7 14623.4 15163.9 15554.8 15609.8 15776.0 14165.6 14311.2 14356.6 14506.9 14531.8 834.0 856.1 858.1 860.1 860.8 677.4 700.1 825.9 852.9 853.6 896.7 900.3 901.1 902.0 903.1 881.0 884.9 889.9 890.6 890.9 661.2 665.7 666.2 666.6 669.6 14933.4 15189.3 15384.2 15461.6 15566.6 14447.0 14773.6 14834.1 14859.0 14921.9 11461.8 11755.8 11815.5 11861.6 11896.0 3585.1 3615.6 3665.6 3702.8 3830.6 111259.4 111883.0 114358.0 115071.0 116782.0 10616.4 10714.8 10735.7 10741.6 10775.9 18410.4 21330.6 21578.5 21630.0 21888.1 6383.5 7429.7 7469.7 7557.9 7650.9 4113.8 4189.2 4192.4 4209.2 4221.6 3030.7 3550.4 3572.9 3578.4 3618.6 643.6 648.4 662.0 662.8 668.0 614.7 617.5 632.0 638.0 643.7 9422.3 10517.5 10916.0 10955.6 11167.6 536.8 565.3 573.8 621.2 674.6 4674.6 4946.6 4947.9 4958.8 4960.5 97626.0 99943.0 100173.0 100213.0 102330.0 68437.3 71541.3 73993.0 84136.6 91753.0 56566.0 78055.6 79371.0 80599.2 82293.2 7565.0 9017.5 9444.9 9458.1 9464.4 51143.7 51566.0 52148.0 52435.5 52805.5 61759.4 65504.7 65738.3 66070.0 66972.3 10419.5 11618.1 11919.2 11975.6 12087.8 12502.9 12696.2 12859.7 12940.9 13639.1 11345.8 11481.6 11986.0 12151.5 12355.7 11869.4 11912.5 11929.6 12015.6 12236.8 11514.7 13224.2 13464.1 13468.9 13489.9 10244.7 10802.7 11096.0 11209.7 11253.2 9863.1 10038.1 10137.3 10935.6 10959.5 09:36:08 INFO - PROCESS | 1920 | -------- Summary: end -------- 09:36:08 INFO - PROCESS | 1920 | 09:36:09 INFO - TEST-INFO | 1920: exit 0 09:36:09 INFO - TEST-OK | dromaeo_css | took 1116576ms 09:36:09 INFO - TEST-START | kraken 09:36:09 INFO - operating with platform_type : mac_ 09:36:09 INFO - Initialising browser for kraken test... 09:36:09 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpEg9OqS/profile http://localhost:49493/getInfo.html) 09:36:12 INFO - PROCESS | 1926 | 2017-02-16 09:36:12.192 plugin-container[1927:14422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b43, name = 'com.apple.tsm.portname' 09:36:12 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:12 INFO - PROCESS | 1926 | 2017-02-16 09:36:12.195 plugin-container[1927:14422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:36:12 INFO - PROCESS | 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:12 INFO - PROCESS | 1926 | 2017-02-16 09:36:12.195 plugin-container[1927:14422] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:12 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 09:36:12 INFO - PROCESS | 1926 | colorDepth:24 09:36:12 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 09:36:12 INFO - PROCESS | 1926 | __metrics 09:36:12 ERROR - PROCESS | 1926 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 09:36:12 INFO - TEST-INFO | 1926: exit 0 09:36:12 INFO - Browser initialized. 09:36:12 INFO - Running cycle 1/1 for kraken test... 09:36:12 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpEg9OqS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:15 INFO - PROCESS | 1928 | 2017-02-16 09:36:15.348 plugin-container[1929:14547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8947, name = 'com.apple.tsm.portname' 09:36:15 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:15 INFO - PROCESS | 1928 | 2017-02-16 09:36:15.348 plugin-container[1929:14547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 09:36:15 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:15 INFO - PROCESS | 1928 | 2017-02-16 09:36:15.349 plugin-container[1929:14547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:25 INFO - PROCESS | 1928 | RSS: Main: 219414528 09:36:25 INFO - PROCESS | 1928 | 09:36:28 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:29 INFO - PROCESS | 1928 | RSS: Main: 233615360 09:36:29 INFO - PROCESS | 1928 | 09:36:31 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-dft) 09:36:32 INFO - PROCESS | 1928 | RSS: Main: 241364992 09:36:32 INFO - PROCESS | 1928 | 09:36:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-fft) 09:36:36 INFO - PROCESS | 1928 | RSS: Main: 242896896 09:36:36 INFO - PROCESS | 1928 | 09:36:38 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-oscillator) 09:36:39 INFO - PROCESS | 1928 | RSS: Main: 244580352 09:36:39 INFO - PROCESS | 1928 | 09:36:41 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:41 INFO - PROCESS | 1928 | RSS: Main: 244899840 09:36:41 INFO - PROCESS | 1928 | 09:36:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:45 INFO - PROCESS | 1928 | RSS: Main: 250073088 09:36:45 INFO - PROCESS | 1928 | 09:36:48 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:49 INFO - PROCESS | 1928 | RSS: Main: 249573376 09:36:49 INFO - PROCESS | 1928 | 09:36:52 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49493/tests/kraken/driver.html?testName=json-parse-financial) 09:36:52 INFO - PROCESS | 1928 | RSS: Main: 249339904 09:36:52 INFO - PROCESS | 1928 | 09:36:54 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:55 INFO - PROCESS | 1928 | RSS: Main: 248975360 09:36:55 INFO - PROCESS | 1928 | 09:36:57 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:57 INFO - PROCESS | 1928 | RSS: Main: 248983552 09:36:57 INFO - PROCESS | 1928 | 09:37:00 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:00 INFO - PROCESS | 1928 | RSS: Main: 249028608 09:37:00 INFO - PROCESS | 1928 | 09:37:03 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:04 INFO - PROCESS | 1928 | RSS: Main: 249384960 09:37:04 INFO - PROCESS | 1928 | 09:37:07 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:08 INFO - PROCESS | 1928 | RSS: Main: 249176064 09:37:08 INFO - PROCESS | 1928 | 09:37:09 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49493/tests/kraken/driver.html?testName=ai-astar) 09:37:10 INFO - PROCESS | 1928 | RSS: Main: 251191296 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | __start_tp_report 09:37:10 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 09:37:10 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 09:37:10 INFO - PROCESS | 1928 | |i|pagename|runs| 09:37:10 INFO - PROCESS | 1928 | |0;ai-astar;105;118;118;117;118;117;117;119;118;119 09:37:10 INFO - PROCESS | 1928 | |1;audio-beat-detection;146;100;127;140;127;106;130;140;163;137 09:37:10 INFO - PROCESS | 1928 | |2;audio-dft;203;199;196;200;178;266;280;258;277;203 09:37:10 INFO - PROCESS | 1928 | |3;audio-fft;82;60;61;90;84;60;59;54;86;82 09:37:10 INFO - PROCESS | 1928 | |4;audio-oscillator;87;71;84;76;63;59;68;60;97;114 09:37:10 INFO - PROCESS | 1928 | |5;imaging-gaussian-blur;92;109;92;132;130;114;94;130;90;87 09:37:10 INFO - PROCESS | 1928 | |6;imaging-darkroom;97;123;130;153;148;144;133;152;152;97 09:37:10 INFO - PROCESS | 1928 | |7;imaging-desaturate;139;145;92;95;101;93;94;90;91;93 09:37:10 INFO - PROCESS | 1928 | |8;json-parse-financial;58;57;58;60;59;61;62;60;61;60 09:37:10 INFO - PROCESS | 1928 | |9;json-stringify-tinderbox;78;71;68;92;110;104;102;79;73;72 09:37:10 INFO - PROCESS | 1928 | |10;stanford-crypto-aes;86;85;88;84;91;88;96;84;84;91 09:37:10 INFO - PROCESS | 1928 | |11;stanford-crypto-ccm;139;139;138;138;160;151;155;163;197;140 09:37:10 INFO - PROCESS | 1928 | |12;stanford-crypto-pbkdf2;174;148;208;238;235;170;172;171;174;170 09:37:10 INFO - PROCESS | 1928 | |13;stanford-crypto-sha256-iterative;66;69;69;70;66;89;71;73;88;76 09:37:10 INFO - PROCESS | 1928 | __end_tp_report 09:37:10 INFO - PROCESS | 1928 | __start_cc_report 09:37:10 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,977 09:37:10 INFO - PROCESS | 1928 | __end_cc_report 09:37:10 INFO - PROCESS | 1928 | __startTimestamp1487266630106__endTimestamp 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | ------- Summary: start ------- 09:37:10 INFO - PROCESS | 1928 | Number of tests: 14 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.00 stddev:4.14 (3.5%) stddev-sans-first:0.78 09:37:10 INFO - PROCESS | 1928 | Values: 105.0 118.0 118.0 117.0 118.0 117.0 117.0 119.0 118.0 119.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:138.50 stddev:18.45 (13.3%) stddev-sans-first:18.81 09:37:10 INFO - PROCESS | 1928 | Values: 146.0 100.0 127.0 140.0 127.0 106.0 130.0 140.0 163.0 137.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#2] audio-dft Cycles:10 Average:226.00 Median:230.50 stddev:39.17 (17.0%) stddev-sans-first:40.65 09:37:10 INFO - PROCESS | 1928 | Values: 203.0 199.0 196.0 200.0 178.0 266.0 280.0 258.0 277.0 203.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#3] audio-fft Cycles:10 Average:71.80 Median:82.00 stddev:14.01 (17.1%) stddev-sans-first:14.36 09:37:10 INFO - PROCESS | 1928 | Values: 82.0 60.0 61.0 90.0 84.0 60.0 59.0 54.0 86.0 82.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:73.50 stddev:17.75 (24.2%) stddev-sans-first:18.52 09:37:10 INFO - PROCESS | 1928 | Values: 87.0 71.0 84.0 76.0 63.0 59.0 68.0 60.0 97.0 114.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#5] imaging-gaussian-blur Cycles:10 Average:107.00 Median:88.50 stddev:18.39 (20.8%) stddev-sans-first:18.69 09:37:10 INFO - PROCESS | 1928 | Values: 92.0 109.0 92.0 132.0 130.0 114.0 94.0 130.0 90.0 87.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#6] imaging-darkroom Cycles:10 Average:132.90 Median:152.00 stddev:21.52 (14.2%) stddev-sans-first:18.50 09:37:10 INFO - PROCESS | 1928 | Values: 97.0 123.0 130.0 153.0 148.0 144.0 133.0 152.0 152.0 97.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:92.50 stddev:20.66 (22.3%) stddev-sans-first:17.41 09:37:10 INFO - PROCESS | 1928 | Values: 139.0 145.0 92.0 95.0 101.0 93.0 94.0 90.0 91.0 93.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:60.00 stddev:1.58 (2.6%) stddev-sans-first:1.56 09:37:10 INFO - PROCESS | 1928 | Values: 58.0 57.0 58.0 60.0 59.0 61.0 62.0 60.0 61.0 60.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#9] json-stringify-tinderbox Cycles:10 Average:84.90 Median:72.50 stddev:15.66 (21.6%) stddev-sans-first:16.41 09:37:10 INFO - PROCESS | 1928 | Values: 78.0 71.0 68.0 92.0 110.0 104.0 102.0 79.0 73.0 72.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.00 stddev:3.97 (4.5%) stddev-sans-first:4.17 09:37:10 INFO - PROCESS | 1928 | Values: 86.0 85.0 88.0 84.0 91.0 88.0 96.0 84.0 84.0 91.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:153.00 stddev:18.54 (12.1%) stddev-sans-first:19.06 09:37:10 INFO - PROCESS | 1928 | Values: 139.0 139.0 138.0 138.0 160.0 151.0 155.0 163.0 197.0 140.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:174.00 stddev:30.28 (17.4%) stddev-sans-first:31.81 09:37:10 INFO - PROCESS | 1928 | Values: 174.0 148.0 208.0 238.0 235.0 170.0 172.0 171.0 174.0 170.0 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - PROCESS | 1928 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:72.00 stddev:8.35 (11.6%) stddev-sans-first:8.38 09:37:10 INFO - PROCESS | 1928 | Values: 66.0 69.0 69.0 70.0 66.0 89.0 71.0 73.0 88.0 76.0 09:37:10 INFO - PROCESS | 1928 | -------- Summary: end -------- 09:37:10 INFO - PROCESS | 1928 | 09:37:10 INFO - TEST-INFO | 1928: exit 0 09:37:11 INFO - TEST-OK | kraken | took 61719ms 09:37:11 INFO - SUITE-END | took 1178s 09:37:11 INFO - Completed test suite (00:19:38) 09:37:11 INFO - javascript benchmark 09:37:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7376.27170008017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.416477116504, 3355.2953879685265, 3374.0, 3392.287619599884, 3415.8804441844527, 5707.343655479621, 5745.396733342996, 5797.507211886984, 5808.82573522794, 5816.825495235144, 5763.366029736736, 5803.187553742479, 5811.680357580342, 5837.649741015532, 5838.678872662002, 5195.142801437768, 5287.814926477574, 5292.603054770908, 5328.0675881720545, 5347.1177255752755, 4072.490938632671, 4268.338407546835, 5221.138512145491, 5366.785328586871, 5398.78404863805, 5148.279240906276, 5188.533032027136, 5220.138677118271, 5246.291750613656, 5258.342707161605, 4268.551802060801, 5265.605079619044, 5276.498732620395, 5322.547583455411, 5348.893022139574, 5134.537891589737, 5186.299849520305, 5226.5818734501145, 5262.23697563855, 5292.92060619091, 4265.637420819234, 5474.0, 5704.429557044304, 5784.768609255625, 5815.621984571032, 6054.666993315366, 6161.815145545641, 6243.313235544087, 6276.184096067482, 6285.245770507523, 5136.717480538568, 6275.278342990523, 6288.962321217039, 6310.211223597051, 6329.0, 5921.703914804242, 5947.910781338283, 5949.9107513387335, 5967.373425790316, 5981.102834574762, 6161.568690191733, 6208.161898143783, 6252.6873656317, 6254.781082662085, 6310.4636105930595, 6189.6286222826775, 6201.379862013853, 6214.906776398358, 6229.252489701174, 6229.314775374705, 6283.0, 6285.685715714196, 6290.21372328459, 6291.213598300213, 6312.810615681537, 6137.202163718777, 6143.078538219303, 6168.568200226031, 6172.8765424691255, 6182.752689892444, 6235.095911092866, 6243.469305109116, 6248.343923888017, 6274.874502510015, 6278.246610406781, 4967.081089998362, 4986.750662466935, 4991.151504244287, 5000.699958002531, 5003.849884503471, 4364.934525982113, 4423.336499525097, 4463.12968971054, 4500.594946454835, 4514.45826500822, 5293.364796224478, 5861.677607731531, 5937.881242375215, 5952.077427998667, 5984.640921544721, 5737.799177028784, 6271.874562508724, 6283.0, 6297.96851015742, 6321.241451025814, 5710.0, 5787.102999035156, 5791.0734282514595, 5827.271591051119, 5870.501007414332, 5515.669059856422, 5533.806316778974, 5543.1408131739645, 5547.389787123413, 5576.749046292926, 6426.325235850169, 6432.0, 6443.130177425987, 6456.741730330734, 6473.320301368289, 6133.273457575459, 6201.441870231701, 6202.162708034538, 6230.657313847782, 6282.43458088756, 6321.936780632319, 6346.619202847844, 6358.1734374531015, 6443.581167224115, 6445.387688169616, 6206.875862482815, 6224.751009959551, 6236.906446403217, 6245.188125543832, 6269.373062693694, 5077.7715002824225, 6200.906986395298, 6215.689215539295, 6253.155823963889, 6267.059941008885, 5280.60395470349, 6085.908711369245, 6109.586862300561, 6129.542179248731, 6156.138140660221, 6224.1597384352535, 6225.159603453476, 6232.439080482597, 6241.344658810759, 6255.093011513396, 6021.789237376671, 6072.05883088122, 6129.172561704291, 6139.171211886517, 6159.692015399301, 5746.597738158289, 6050.90923636137, 6108.251008765869, 6126.724297406538, 6142.928535732134, 4708.623310135249, 6105.114758360101, 6105.6947152641305, 6123.479504242189, 6133.53998450127], "value": 5704.360071787233, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.8159440209919, 492.8176574667374, 495.6505663507228, 505.6157320436468, 514.6628958032487, 39507.60492395075, 42208.577914220885, 42386.99999999996, 42438.0, 42556.78721606395, 43107.78446107765, 43986.78006609962, 44094.33858492125, 44111.0, 44120.55879441205, 8551.273141782956, 9153.67962121326, 9507.952460237724, 9512.09635084666, 9521.0, 8534.487930724146, 11325.490352934137, 11558.306501609888, 11560.190786644938, 11764.647060588197, 11665.533378664843, 11693.649190524298, 11749.823752643717, 11764.058875289957, 11768.646940591796, 11574.6527604172, 11596.652100436959, 11652.533898644044, 11656.825147622792, 11690.766184676258, 11431.314121152758, 11450.828237576443, 11503.309801411859, 11520.654380368602, 11524.366159861203, 41131.38302925458, 41287.0, 41464.37803432951, 41495.0, 41552.37671434931, 3931.4495970564144, 3966.3257246268204, 3966.458405126143, 4004.3192657248337, 4013.819378127991, 29997.0, 30276.54585181224, 30967.845160774275, 32027.0, 32054.0, 3215.083701145162, 3226.6934641209045, 3234.644189139193, 3253.9511907321407, 3266.1344743642953, 489017.0, 494660.0, 495312.0, 496567.0, 504772.0, 67677.0, 68055.65972170107, 68143.0, 68164.97752533716, 68294.0, 70594.64702676453, 71339.28660713327, 71346.28653713396, 71850.28149718541, 71882.64058679673, 16402.753958690628, 16881.987080775194, 16896.746548801777, 16913.7462938056, 16995.660086798194, 11598.478068486938, 11939.46272417726, 12547.686307842194, 12931.935340323427, 13010.869891301156, 18330.816691833177, 18576.0, 18705.0, 18823.34118305853, 19007.429777106434, 5884.911291411103, 6548.345165483413, 6687.264400915896, 6796.660166991631, 6816.829579260559, 712.0245263988369, 876.8640860666607, 888.3781353052889, 911.5578944212052, 917.8256131335025, 29509.0, 30549.38901221963, 30604.69395306063, 31002.379952400825, 31232.219194519865, 836.5984327522701, 1074.9247552671395, 1092.7213560542011, 1100.1254003067581, 1180.4805885410392, 14083.85916140846, 15249.847501524842, 15665.530034098996, 15985.44050958211, 16050.277737501841, 591608.0, 654118.0, 671781.0, 672959.0, 847617.0, 42259.7887010563, 54914.72542637261, 55350.44649553533, 55361.0, 55539.444605554236, 1285.5372066056211, 1296.3388671777461, 1403.4175817035816, 1413.3216056293038, 1433.5770947570493, 26226.688665566264, 26984.865075674497, 29355.11934641964, 29501.85249073741, 30210.84894575513, 48341.5165848337, 48511.75744121257, 48846.0, 48848.267275990176, 48939.265911010654, 433487.0, 450398.74800627097, 516308.0, 516740.0, 517259.0, 22464.88767556217, 25705.742942570334, 25836.483270334113, 26530.86734566392, 26579.60130598004, 42717.57282427136, 43290.56709432865, 44605.77697111494, 44877.0, 44914.55085449103, 30655.2336191597, 31137.221569460038, 34757.9572612822, 35105.473417899266, 37087.6291237095, 32091.839540802146, 33953.49069764005, 34419.483707744905, 36730.63269367379, 37009.07477313089, 34649.96050118501, 35364.115897102776, 36129.096772580895, 36159.27681446304, 36315.72894948664, 30391.240218994702, 30496.390072197988, 32191.0, 32430.35139297248, 33165.0, 24104.638430423907, 24220.39449013789, 24374.634380483953, 24471.265862024167, 24576.385590360384], "value": 20068.42805020429, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.98108104054273, 374.1898789121552, 375.0118437916092, 377.51866370377763, 402.259841890921, 11586.710332241688, 12066.095042871786, 12153.696157596065, 12188.085893557974, 19606.803931960676, 22298.0, 22733.31800045997, 23289.0, 23412.180573679925, 23810.404739881513, 5599.992001439739, 6483.092367068614, 6751.257361690235, 6761.357671021246, 6776.559523630973, 6610.867782644368, 6717.529772915897, 6763.154605674291, 6773.695183716744, 6783.219929708072, 5918.644881307136, 6207.844803879917, 6622.602643841385, 6756.223034351064, 6775.559588626748, 6502.674866256668, 6531.443808610735, 6583.736650533949, 6588.143541339643, 6589.835254118638, 5662.235598194274, 6481.319461456526, 6699.464042876558, 6708.698108585126, 6717.328267173243, 22267.44331391712, 28917.421651566852, 29245.853770731224, 29396.971106011188, 29500.11499655014, 2891.956620650692, 2926.4732348177317, 2942.955855662167, 2946.8379239141836, 2954.7193016663596, 6102.267727872683, 10342.7414314642, 16509.996422751294, 17150.485485435456, 17722.822771772248, 1892.5552495163627, 1947.8928658923753, 1953.4237399967044, 1956.3055115433958, 1972.891490967996, 111938.88061119366, 116080.8391916087, 136867.31566342205, 157845.21077394654, 161143.0, 24383.634245486333, 24735.010599576177, 24887.50224995526, 24910.252692419257, 25081.87459062693, 42979.57020429818, 43023.35464968028, 43060.35409468798, 44057.55942440599, 44063.55936440659, 8549.871751923725, 8812.603432845537, 9010.053944335881, 9073.092690730875, 9156.908430915739, 8650.74047778555, 8713.085126061798, 8722.433041852386, 8844.0, 8886.73339799807, 4790.353302304214, 4899.1426500363, 4904.068227036852, 4932.432770231398, 4982.875428114254, 1752.4567384110967, 2029.9086541105685, 2054.815066644009, 2074.8236399905877, 2083.4895450614695, 928.6146249306597, 968.3512046928574, 989.42118860466, 997.1623835977668, 1038.1746511523356, 21665.025073871715, 21684.855695924, 21892.89053554722, 21978.0, 22260.777392226195, 1009.1170226052204, 1019.37308555238, 1024.262530977695, 1049.816282150636, 1055.2929537209934, 11146.554137834395, 11375.26060806045, 11423.942880285711, 11451.0, 11541.826872596917, 432425.0, 682644.0, 702002.0, 738433.0, 738717.0, 124976.75023249652, 148060.25969870083, 148179.0, 156594.21702891414, 159767.40232597524, 996.6163027234422, 1011.6408674920298, 1023.2070145637191, 1030.6753372687579, 1030.7577719235974, 19209.90395048072, 20422.945094742263, 20601.07295171751, 20683.896580517, 20694.275700350416, 60627.69686151717, 62188.0, 62370.376296238275, 62569.68715156395, 64532.677336613015, 86480.27039459301, 88467.67298490656, 89840.5507972456, 90166.54916725375, 90763.54618226866, 8122.878156827771, 8170.83658326842, 8252.876206857021, 8391.076981531905, 8417.87373189415, 10601.41692206936, 12206.938965305117, 12381.380930953597, 12489.250644961332, 12631.621051368473, 10039.49802509857, 10354.585816567253, 10395.0, 10456.63401780934, 10947.124230061736, 10690.946545267223, 10814.729631759268, 10820.837687434538, 11004.724881878017, 11166.776664466503, 10609.840852387375, 10821.729456763329, 10890.0, 11160.832587511031, 11206.103511719206, 6323.936760632519, 7107.786766397016, 7722.227777222458, 8348.33213342919, 8429.4942303462, 7712.344450721527, 9373.1564159226, 10521.421321827373, 10950.780984380428, 11335.603253886075], "value": 10632.395307826899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.2942352282719, 1261.6845788552855, 1286.5239861251337, 1427.6288165077076, 1431.1270125223612, 7176.0, 7618.657160427781, 7896.763097107081, 8054.556999365032, 8593.871091933619, 7383.261673832614, 8003.91996080039, 8016.516944365297, 8365.498070115797, 8399.328053755686, 1957.4323446200585, 2292.2321022457454, 2347.1667558016916, 2353.9058437662475, 2356.3166681662296, 2069.1102825784906, 2160.9891950540305, 2326.6626339180807, 2375.192434572245, 2381.9638457271058, 2267.6938613287243, 2349.400902769791, 2349.906003759856, 2355.776201260886, 2357.7170739511284, 2332.7084114485688, 2339.3800642829665, 2351.318117745852, 2354.693889794333, 2358.9882050589636, 1989.641864464396, 2332.650102484624, 2375.4298968247667, 2381.404648837791, 2387.6179811230118, 8472.576371181478, 8495.532745698983, 8537.317014638815, 8544.914550854473, 8548.957255213747, 4928.827491037797, 5067.163917953533, 5067.721275329855, 5073.594112470993, 5086.567641750456, 3257.348530293927, 3461.238527523941, 3514.6309637487952, 3621.7102631789394, 3623.384024715778, 1054.4464156317918, 1183.1717797541755, 1193.6717402714237, 1251.3242848861603, 1256.7298030923396, 10708.19688523364, 10798.298110622783, 10939.398333091676, 10948.945255273673, 10971.945140274327, 4707.835225767081, 4724.141894154056, 4743.573078422958, 4800.783964721596, 4838.080764654705, 9626.9037309626, 9704.902950970541, 9732.90267097334, 9756.560954757053, 9757.951210243904, 5254.868628284324, 5357.196420536873, 5359.249705041298, 5364.383095943938, 5381.623286369995, 4591.586757191802, 4782.234842425195, 4830.734309613005, 4862.294967229732, 4891.1685013547785, 3740.1958578905665, 3934.016495876031, 4000.7999600019884, 4086.162336720965, 4107.691923105779, 1155.930644161353, 1179.7463545337794, 1222.9816552751715, 1237.5049980007889, 1289.4777615065914, 861.8664107063415, 873.3406278259873, 875.7372788163525, 892.9553522323857, 895.1809094678297, 4841.757912104381, 5149.948500514947, 5370.597205209547, 5505.697186654692, 5506.449355064542, 941.9952900235455, 984.158544444504, 989.2728844299434, 998.6854140945724, 998.8962196772462, 3221.0, 3231.1275955492356, 3486.9825650871585, 3649.3248748981523, 3665.9633403666157, 9865.309428339948, 10160.949195254125, 10161.745956351151, 10184.694459166238, 10215.7446063849, 9483.76290592727, 9860.65487707941, 9937.950310248403, 10043.19654427644, 10126.493675316206, 7191.712331506786, 7375.008747638215, 7626.504277221963, 7895.802604934923, 7915.525068495908, 5017.67385119966, 5372.167314066326, 5436.0, 5592.776288948559, 5594.496495315441, 8931.19619234272, 9011.91856977167, 9086.0, 9324.067593240892, 9369.016253293306, 7870.9606451967375, 9130.908690913006, 9373.0, 9483.66807161746, 9500.572474238537, 7166.605836679033, 7268.200497945116, 7268.382187513909, 7311.4150867929375, 7321.78034658961, 7572.848543028998, 7775.0, 7833.843323133619, 7868.0, 7888.2506161914525, 7074.71701131949, 7120.215547983274, 7122.893156602759, 7190.712371505081, 7259.274072592699, 6973.919042548413, 6997.265287144776, 7001.754938577125, 7038.2961703829205, 7171.1394632644415, 5335.866603334947, 6344.936550634619, 6347.206599175103, 6355.5551111421755, 6381.680915954276, 4067.51189857219, 4751.216049351819, 4782.282657601388, 4816.4220293564995, 4886.902261954812, 4825.9758701206265, 4868.926966095441, 4935.703857768545, 5021.1213037717525, 5052.216906379517], "value": 4386.766764193093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.55396398240555, 351.4429629037975, 359.43209728628767, 369.04601604851456, 373.95886452490237, 5215.426303106655, 5308.283381743468, 5344.679319240849, 5396.763540620614, 5532.917006244909, 4531.909361812762, 5159.993801208766, 5283.735813209334, 5327.893442131155, 5456.127019676853, 4232.76719780412, 4240.300350442173, 4253.51084625269, 4265.914681706364, 4269.648326064041, 3908.218356328746, 4116.279651061067, 4176.665866730655, 4178.8328466861285, 4254.872353829374, 3670.853165873362, 4170.645495132917, 4200.201961627296, 4202.432671589327, 4204.76873771944, 3544.946825797615, 3994.6018893387372, 4189.371594260855, 4205.7897105144775, 4208.516020657633, 3773.3019391412677, 3776.150366167618, 3776.4146557283666, 3784.6215378462207, 3788.753731007476, 4285.114549598166, 4591.242444996572, 5074.797008119671, 5163.896722065575, 5255.106631872591, 4462.776861156929, 4859.246816743411, 4888.486708895551, 4891.5353041461, 4913.459519452857, 5434.212039254326, 5469.0, 5736.598438109334, 5782.56630752691, 5816.993137367602, 3967.3057214987552, 4012.469027255996, 4100.34394496882, 4130.0, 4150.522689890671, 4923.901521969577, 4998.875028124289, 5982.252218472691, 6003.559145804976, 6067.0, 3781.4327850822046, 3913.8434462621176, 4232.0054787125, 4240.1519696060295, 4277.8288868444915, 3559.964400356015, 3561.468568515563, 4074.3888416737727, 4083.8774836754947, 4288.378185163134, 4352.347147927836, 4585.784767036738, 4660.39414876064, 4865.12427762999, 4890.706557606555, 4917.606591472673, 5782.479576838105, 5795.507381872504, 5835.708214589254, 5842.678652674059, 1185.8319555238083, 1224.9142560020714, 1246.8902676617818, 1328.2429015461094, 1384.847666756656, 840.7561807076002, 859.4971941414218, 952.8618350339301, 955.5556666150273, 985.69936169469, 4191.224623444673, 5003.023805596842, 5102.285680004802, 5119.513646203604, 5127.410347809975, 4560.612347950461, 5159.8132429540665, 5178.870528236749, 5345.1982202669915, 5356.143017117242, 4263.616274535307, 4758.952410475851, 4953.752312384367, 5121.359830021247, 5158.793648254103, 2503.962440563393, 2652.4827658606773, 2964.2293003819163, 2971.539411391238, 2974.479466093425, 4921.581665558396, 5023.0, 5225.712585807741, 5823.88352232953, 5847.26909136358, 5549.114460480528, 5636.239107720406, 5768.9711551442815, 5799.8250656004, 5837.9124313135335, 5103.647533403651, 5206.401263854629, 5555.2222688822785, 5694.886102277931, 5793.681347525927, 4849.660523763303, 5603.775848966159, 5617.455199820082, 5686.43135686428, 5686.606781338599, 4918.483559226373, 5171.612129090408, 5330.577687254052, 5551.528120109836, 5571.3871474137, 4601.631869450369, 4830.3237546742785, 5369.946300536944, 5584.972075139598, 5702.629329093762, 3696.9445458318683, 3869.6323849234277, 4013.5785742497787, 4051.372037334218, 4058.736182148151, 4927.678107643807, 5324.0, 5587.329520457572, 5587.563648356313, 5599.524040456607, 5536.944630553643, 5598.86002849932, 5660.15097735343, 5697.487226149667, 5742.449538624464, 4878.926816097691, 5707.914381284201, 5717.942180696585, 5723.7996670116345, 5728.519300557584, 5301.708406037705, 5399.973000134974, 5592.580556458363, 5610.607257491936, 5627.260194601566, 5548.805791797267, 5729.736753982237, 5781.017227071324, 5788.102844059177, 5795.420457954171, 5004.523665518608, 5498.75573411673, 5614.529606972919, 5642.6614403135945, 5709.658230315956], "value": 4138.168682894016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.1993707236674, 700.1633048507036, 754.5548126605302, 757.9768154088968, 846.7036537211975, 14623.415063397464, 15163.92418037911, 15554.84445155548, 15609.843901560995, 15776.0, 14165.575032749008, 14311.21288329144, 14356.569302920903, 14506.854931450682, 14531.782023269632, 833.9791505212357, 856.0626114404748, 858.0989960541438, 860.0582362313257, 860.7546849147994, 677.427573700225, 700.0619170311782, 825.8513467575834, 852.8891244138254, 853.5774791478227, 896.6503063805121, 900.3157600223811, 901.0944001278706, 902.0483390023522, 903.0788595632451, 881.0176653031888, 884.884964954555, 889.937704360695, 890.5947793753891, 890.9198172164504, 661.2230629010893, 665.6605131382981, 666.1739443090532, 666.5667316244432, 669.581511555278, 14933.402663893432, 15189.316480758393, 15384.15387153706, 15461.613459663486, 15566.610834729108, 14446.988710790249, 14773.556793296219, 14834.109953402722, 14859.034162779384, 14921.850781492154, 11461.828072578917, 11755.764884702343, 11815.527378904833, 11861.644150675493, 11896.04831613469, 3585.121645196917, 3615.6022837487853, 3665.596784353719, 3702.7593206441493, 3830.5977872323283, 111259.44370278259, 111883.0, 114358.0, 115070.97251596238, 116782.0, 10616.416097114577, 10714.839277410845, 10735.731606709895, 10741.570337186426, 10775.946120269506, 18410.447686569423, 21330.57338853245, 21578.46053848635, 21630.0, 21888.12447502114, 6383.489320854321, 7429.665665044978, 7469.7385591504035, 7557.886631700529, 7650.923490765133, 4113.753174809521, 4189.2040512302565, 4192.413062171298, 4209.1792100540715, 4221.64116050133, 3030.65147508035, 3550.396432606463, 3572.8566858604995, 3578.3916734155255, 3618.620044895301, 643.6009674002148, 648.3516483516484, 662.0499583098269, 662.7672529095878, 667.9732810687615, 614.6926536731634, 617.5090802811775, 632.0, 637.9936200638027, 643.7360682120402, 9422.29332800029, 10517.474126293808, 10916.0, 10955.561777528968, 11167.553297868157, 536.8013834881119, 565.2538648983302, 573.7966209754562, 621.201755743867, 674.6289540752566, 4674.579287864109, 4946.629002824802, 4947.90104197914, 4958.801647934114, 4960.50394960501, 97626.0, 99943.0, 100173.0, 100213.0, 102330.0, 68437.3156268441, 71541.28458715347, 73993.0, 84136.57931710302, 91753.0, 56566.0, 78055.60972195103, 79371.0, 80599.19400805917, 82293.17706822975, 7565.0, 9017.50403727775, 9444.858327125236, 9458.054194580487, 9464.384814987005, 51143.74428127985, 51565.968680625425, 52148.0, 52435.47564524306, 52805.47194528006, 61759.38240617716, 65504.672476637315, 65738.34261657322, 66070.0, 66972.33027669661, 10419.479026048819, 11618.0705543558, 11919.225250358699, 11975.64073077809, 12087.75824483523, 12502.874971250534, 12696.17474864131, 12859.678508037374, 12940.935295323463, 13639.113457625619, 11345.829812552653, 11481.598144064918, 11986.0, 12151.453184606891, 12355.69110772202, 11869.406529673655, 11912.463939122934, 11929.642110736691, 12015.639530814089, 12236.841452709601, 11514.712132196762, 13224.206547607175, 13464.057515973787, 13468.865311347154, 13489.865101348862, 10244.692659220234, 10802.67591972242, 11096.0, 11209.663710088711, 11253.1560132992, 9863.063009014133, 10038.146757525692, 10137.341072830242, 10935.56257749681, 10959.452027398758], "value": 7289.007878004183, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1611.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 119.0, 118.0, 119.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 100.0, 127.0, 140.0, 127.0, 106.0, 130.0, 140.0, 163.0, 137.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 199.0, 196.0, 200.0, 178.0, 266.0, 280.0, 258.0, 277.0, 203.0], "value": 226.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 90.0, 84.0, 60.0, 59.0, 54.0, 86.0, 82.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 84.0, 76.0, 63.0, 59.0, 68.0, 60.0, 97.0, 114.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 109.0, 92.0, 132.0, 130.0, 114.0, 94.0, 130.0, 90.0, 87.0], "value": 107.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 123.0, 130.0, 153.0, 148.0, 144.0, 133.0, 152.0, 152.0, 97.0], "value": 132.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 145.0, 92.0, 95.0, 101.0, 93.0, 94.0, 90.0, 91.0, 93.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 60.0, 59.0, 61.0, 62.0, 60.0, 61.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 68.0, 92.0, 110.0, 104.0, 102.0, 79.0, 73.0, 72.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 88.0, 84.0, 91.0, 88.0, 96.0, 84.0, 84.0, 91.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 138.0, 138.0, 160.0, 151.0, 155.0, 163.0, 197.0, 140.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 148.0, 208.0, 238.0, 235.0, 170.0, 172.0, 171.0, 174.0, 170.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 69.0, 70.0, 66.0, 89.0, 71.0, 73.0, 88.0, 76.0], "value": 73.7, "unit": "score"}]}]} 09:37:11 INFO - Return code: 0 09:37:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:11 ERROR - # TBPL SUCCESS # 09:37:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:11 INFO - 2017-02-16 09:37:11.423 screenresolution[1933:14931] starting screenresolution argv=screenresolution get 09:37:11 INFO - 2017-02-16 09:37:11.426 screenresolution[1933:14931] Display 0: 1600x1200x32@60 09:37:11 INFO - 2017-02-16 09:37:11.433 screenresolution[1934:14934] starting screenresolution argv=screenresolution list 09:37:11 INFO - Available Modes on Display 0 09:37:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:11 INFO - Intel Iris: 09:37:11 INFO - Chipset Model: Intel Iris 09:37:11 INFO - Type: GPU 09:37:11 INFO - Bus: Built-In 09:37:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:11 INFO - Vendor: Intel (0x8086) 09:37:11 INFO - Device ID: 0x0a2e 09:37:11 INFO - Revision ID: 0x0009 09:37:11 INFO - Displays: 09:37:11 INFO - Display: 09:37:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:11 INFO - Display Serial Number: 1600x1200 60 09:37:11 INFO - Main Display: Yes 09:37:11 INFO - Mirror: Off 09:37:11 INFO - Online: Yes 09:37:11 INFO - Rotation: Supported 09:37:11 INFO - Return code: 0 09:37:11 INFO - Running post-action listener: _resource_record_post_action 09:37:11 INFO - [mozharness: 2017-02-16 17:37:11.671615Z] Finished run-tests step (success) 09:37:11 INFO - Running post-run listener: _resource_record_post_run 09:37:11 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 2201088; Write bytes: 511909376; Read time: 14; Write time: 1623 09:37:11 INFO - TinderboxPrint: CPU usage
54.3% 09:37:11 INFO - TinderboxPrint: I/O read bytes / time
2,201,088 / 14 09:37:11 INFO - TinderboxPrint: I/O write bytes / time
511,909,376 / 1,623 09:37:11 INFO - TinderboxPrint: CPU idle
2,192.2 (45.7%) 09:37:11 INFO - TinderboxPrint: CPU system
966.4 (20.1%) 09:37:11 INFO - TinderboxPrint: CPU user
1,639.1 (34.2%) 09:37:11 INFO - TinderboxPrint: Swap in / out
209,829,888 / 0 09:37:11 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203186688; Write bytes: 202033152; Read time: 15581; Write time: 439 09:37:11 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1336832; Write bytes: 299001344; Read time: 11; Write time: 1145 09:37:12 INFO - Running post-run listener: _upload_blobber_files 09:37:12 INFO - Blob upload gear active. 09:37:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:37:12 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:37:12 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:37:12 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:37:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:13 INFO - (blobuploader) - INFO - Done attempting. 09:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:14 INFO - (blobuploader) - INFO - Done attempting. 09:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:15 INFO - (blobuploader) - INFO - Done attempting. 09:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:17 INFO - (blobuploader) - INFO - Done attempting. 09:37:17 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:17 INFO - Return code: 0 09:37:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b75f767c3818e474a35509a33b8fd4e3187f929b9234683b237eee05d3118d467b489c9762cadc733ab7824403c992b8a1046a8afabc0ef5be2cad92bef8799", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b319de4a269c801fb650e1344b0aec10f3f6d87e9e85ff5229275dc5c0266c588f30954855d44548c603547569b164bffb71059c4e122e2f88a19b32cbcb6ab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/758365e3cbf7dea9a1ce9d4ed7f501cc3da28f011153dfff3af545954258ad03dd3a03fa518b22bc846cfb4bac85bf74fef55560c78ac2ab0118044e10619d41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/479e702b694e810a4d665147299a9e681f31b4e47efd5bca0a90e073361a657430aff4a7611620a7523b9c8be16af14a80585a39f56e7ae097bb4bad3fbfcb7f"} 09:37:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:17 INFO - Contents: 09:37:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b75f767c3818e474a35509a33b8fd4e3187f929b9234683b237eee05d3118d467b489c9762cadc733ab7824403c992b8a1046a8afabc0ef5be2cad92bef8799", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b319de4a269c801fb650e1344b0aec10f3f6d87e9e85ff5229275dc5c0266c588f30954855d44548c603547569b164bffb71059c4e122e2f88a19b32cbcb6ab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/758365e3cbf7dea9a1ce9d4ed7f501cc3da28f011153dfff3af545954258ad03dd3a03fa518b22bc846cfb4bac85bf74fef55560c78ac2ab0118044e10619d41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/479e702b694e810a4d665147299a9e681f31b4e47efd5bca0a90e073361a657430aff4a7611620a7523b9c8be16af14a80585a39f56e7ae097bb4bad3fbfcb7f"} 09:37:17 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:17 INFO - Copying logs to upload dir... 09:37:17 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.884241 ========= master_lag: 1.53 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-16 09:37:19.354243) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:37:19.359166) ========= 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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-Aurora/sha512/9b75f767c3818e474a35509a33b8fd4e3187f929b9234683b237eee05d3118d467b489c9762cadc733ab7824403c992b8a1046a8afabc0ef5be2cad92bef8799", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b319de4a269c801fb650e1344b0aec10f3f6d87e9e85ff5229275dc5c0266c588f30954855d44548c603547569b164bffb71059c4e122e2f88a19b32cbcb6ab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/758365e3cbf7dea9a1ce9d4ed7f501cc3da28f011153dfff3af545954258ad03dd3a03fa518b22bc846cfb4bac85bf74fef55560c78ac2ab0118044e10619d41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/479e702b694e810a4d665147299a9e681f31b4e47efd5bca0a90e073361a657430aff4a7611620a7523b9c8be16af14a80585a39f56e7ae097bb4bad3fbfcb7f"} build_url:https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011152 build_url: 'https://queue.taskcluster.net/v1/task/AI3csdEBTJWqq6xAd0V81g/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b75f767c3818e474a35509a33b8fd4e3187f929b9234683b237eee05d3118d467b489c9762cadc733ab7824403c992b8a1046a8afabc0ef5be2cad92bef8799", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b319de4a269c801fb650e1344b0aec10f3f6d87e9e85ff5229275dc5c0266c588f30954855d44548c603547569b164bffb71059c4e122e2f88a19b32cbcb6ab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/758365e3cbf7dea9a1ce9d4ed7f501cc3da28f011153dfff3af545954258ad03dd3a03fa518b22bc846cfb4bac85bf74fef55560c78ac2ab0118044e10619d41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/479e702b694e810a4d665147299a9e681f31b4e47efd5bca0a90e073361a657430aff4a7611620a7523b9c8be16af14a80585a39f56e7ae097bb4bad3fbfcb7f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:37:19.397931) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:37:19.398249) ========= 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.ZcmVrDSBTJ/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.T12XLC9h2x/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.005246 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:37:19.446898) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:37:19.447222) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:37:19.447539) ========= ========= Total master_lag: 1.67 =========