builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0346 starttime: 1470957031.11 results: success (0) buildid: 20160811135022 builduid: f979b05a50f2425ab25770ea7b2ec8b9 revision: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.112773) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.113226) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.113518) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.136456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.136773) ========= 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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 --2016-08-11 16:10:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-08-11 16:10:31 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.281407) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.281733) ========= 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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.020763 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:31.330125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 16:10:31.330483) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 2016-08-11 16:10:31,414 truncating revision to first 12 chars 2016-08-11 16:10:31,414 Setting DEBUG logging. 2016-08-11 16:10:31,414 attempt 1/10 2016-08-11 16:10:31,414 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/233ab21b64b5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 16:10:32,174 unpacking tar archive at: mozilla-central-233ab21b64b5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.031682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 16:10:32.384308) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:32.384646) ========= script_repo_revision: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:32.385039) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:32.385308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 16:10:32.403361) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-11 16:10:32.403804) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:10:32 INFO - MultiFileLogger online at 20160811 16:10:32 in /builds/slave/test 16:10:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:10:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:32 INFO - {'append_to_log': False, 16:10:32 INFO - 'base_work_dir': '/builds/slave/test', 16:10:32 INFO - 'blob_upload_branch': 'Firefox', 16:10:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:32 INFO - 'branch': 'Firefox', 16:10:32 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:32 INFO - 'config_files': ('talos/mac_config.py',), 16:10:32 INFO - 'default_actions': ('clobber', 16:10:32 INFO - 'read-buildbot-config', 16:10:32 INFO - 'download-and-extract', 16:10:32 INFO - 'populate-webroot', 16:10:32 INFO - 'create-virtualenv', 16:10:32 INFO - 'install', 16:10:32 INFO - 'run-tests'), 16:10:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:32 INFO - 'download_minidump_stackwalk': True, 16:10:32 INFO - 'download_symbols': 'ondemand', 16:10:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:32 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:32 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:32 INFO - 'installer_path': 'installer.exe', 16:10:32 INFO - 'log_level': 'info', 16:10:32 INFO - 'log_name': 'talos', 16:10:32 INFO - 'log_to_console': True, 16:10:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:32 INFO - 'opt_config_files': (), 16:10:32 INFO - 'pip_index': False, 16:10:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:32 INFO - 'cmd': ('bash', 16:10:32 INFO - '-c', 16:10:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:32 INFO - 'enabled': True, 16:10:32 INFO - 'halt_on_failure': False, 16:10:32 INFO - 'name': 'check_screen_resolution'},), 16:10:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:32 INFO - 'cmd': ('bash', 16:10:32 INFO - '-c', 16:10:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:32 INFO - 'enabled': True, 16:10:32 INFO - 'halt_on_failure': False, 16:10:32 INFO - 'name': 'check_screen_resolution'},), 16:10:32 INFO - 'run_cmd_checks_enabled': True, 16:10:32 INFO - 'sps_profile': False, 16:10:32 INFO - 'sps_profile_interval': 0, 16:10:32 INFO - 'suite': 'dromaeojs', 16:10:32 INFO - 'system_bits': '32', 16:10:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:32 INFO - 'title': 't-yosemite-r7-0346', 16:10:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:32 INFO - 'use_talos_json': True, 16:10:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:32 INFO - 'work_dir': 'build'} 16:10:32 INFO - [mozharness: 2016-08-11 23:10:32.599001Z] Running clobber step. 16:10:32 INFO - Running pre-action listener: _resource_record_pre_action 16:10:32 INFO - Running main action method: clobber 16:10:32 INFO - rmtree: /builds/slave/test/build 16:10:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:33 INFO - Running post-action listener: _resource_record_post_action 16:10:33 INFO - [mozharness: 2016-08-11 23:10:33.742896Z] Finished clobber step (success) 16:10:33 INFO - [mozharness: 2016-08-11 23:10:33.743024Z] Running read-buildbot-config step. 16:10:33 INFO - Running pre-action listener: _resource_record_pre_action 16:10:33 INFO - Running main action method: read_buildbot_config 16:10:33 INFO - Using buildbot properties: 16:10:33 INFO - { 16:10:33 INFO - "project": "", 16:10:33 INFO - "product": "firefox", 16:10:33 INFO - "script_repo_revision": "production", 16:10:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:10:33 INFO - "repository": "", 16:10:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:10:33 INFO - "buildid": "20160811135022", 16:10:33 INFO - "pgo_build": "False", 16:10:33 INFO - "basedir": "/builds/slave/test", 16:10:33 INFO - "buildnumber": 32, 16:10:33 INFO - "slavename": "t-yosemite-r7-0346", 16:10:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:10:33 INFO - "platform": "yosemite_r7", 16:10:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:10:33 INFO - "branch": "mozilla-central", 16:10:33 INFO - "repo_path": "mozilla-central", 16:10:33 INFO - "revision": "233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4", 16:10:33 INFO - "stage_platform": "macosx64", 16:10:33 INFO - "builduid": "f979b05a50f2425ab25770ea7b2ec8b9", 16:10:33 INFO - "slavebuilddir": "test" 16:10:33 INFO - } 16:10:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:10:33 INFO - Running post-action listener: _resource_record_post_action 16:10:33 INFO - [mozharness: 2016-08-11 23:10:33.746181Z] Finished read-buildbot-config step (success) 16:10:33 INFO - [mozharness: 2016-08-11 23:10:33.746290Z] Running download-and-extract step. 16:10:33 INFO - Running pre-action listener: _resource_record_pre_action 16:10:33 INFO - Running main action method: download_and_extract 16:10:33 INFO - mkdir: /builds/slave/test/build/tests 16:10:33 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')]} 16:10:33 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:10:33 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 16:10:35 INFO - Downloaded 1199 bytes. 16:10:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:10:35 INFO - Using the following test package requirements: 16:10:35 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:10:35 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:10:35 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:10:35 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'jsshell-mac.zip'], 16:10:35 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:10:35 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:10:35 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:10:35 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:10:35 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:10:35 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:10:35 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:10:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:10:35 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')]} 16:10:35 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:10:35 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:10:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:36 INFO - Downloaded 16328348 bytes. 16:10:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:10:39 INFO - Return code: 0 16:10:39 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:39 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:10:39 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:10:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:40 INFO - Downloaded 16328348 bytes. 16:10:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:10:43 INFO - Return code: 0 16:10: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')]} 16:10:43 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:10:44 INFO - Downloaded 14413719 bytes. 16:10:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:10:44 INFO - Return code: 0 16:10: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')]} 16:10:44 INFO - trying https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:44 INFO - Downloading https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 16:10:47 INFO - Downloaded 110920211 bytes. 16:10:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:47 INFO - mkdir: /builds/slave/test/properties 16:10:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:47 INFO - Contents: 16:10:47 INFO - build_url:https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:10:48 INFO - Running post-action listener: _resource_record_post_action 16:10:48 INFO - Running post-action listener: set_extra_try_arguments 16:10:48 INFO - [mozharness: 2016-08-11 23:10:48.215979Z] Finished download-and-extract step (success) 16:10:48 INFO - [mozharness: 2016-08-11 23:10:48.216111Z] Running populate-webroot step. 16:10:48 INFO - Running pre-action listener: _resource_record_pre_action 16:10:48 INFO - Running main action method: populate_webroot 16:10:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:48 INFO - '--tppagecycles', 16:10:48 INFO - '7'], 16:10:48 INFO - 'tests': ['tsvgm']}}, 16:10:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['damp', 'tps']}, 16:10:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'tests': ['damp', 'tps']}, 16:10:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['dromaeo_dom']}, 16:10:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['basic_compositor_video']}, 16:10:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['a11yr', 16:10:48 INFO - 'ts_paint', 16:10:48 INFO - 'tpaint', 16:10:48 INFO - 'sessionrestore', 16:10:48 INFO - 'sessionrestore_no_auto_restore', 16:10:48 INFO - 'tabpaint']}, 16:10:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['tsvgx', 16:10:48 INFO - 'tsvgr_opacity', 16:10:48 INFO - 'tart', 16:10:48 INFO - 'tscrollx', 16:10:48 INFO - 'cart']}, 16:10:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:48 INFO - 'tsvgr_opacity', 16:10:48 INFO - 'tart', 16:10:48 INFO - 'tscrollx', 16:10:48 INFO - 'cart']}, 16:10:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'talos_options': ['--disable-e10s'], 16:10:48 INFO - 'tests': ['tp5o']}, 16:10:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'tests': ['tp5o']}, 16:10:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'talos_options': ['--disable-e10s', 16:10:48 INFO - '--xperf_path', 16:10:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:48 INFO - 'tests': ['tp5n']}, 16:10:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:48 INFO - 'talos_options': ['--xperf_path', 16:10:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:48 INFO - 'tests': ['tp5n']}}, 16:10:48 INFO - 'talos.zip': {'path': '', 16:10:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:48 INFO - Running post-action listener: _resource_record_post_action 16:10:48 INFO - [mozharness: 2016-08-11 23:10:48.235350Z] Finished populate-webroot step (success) 16:10:48 INFO - [mozharness: 2016-08-11 23:10:48.235461Z] Running create-virtualenv step. 16:10:48 INFO - Running pre-action listener: _resource_record_pre_action 16:10:48 INFO - Running main action method: create_virtualenv 16:10:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:48 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 16:10:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:48 INFO - Using real prefix '/tools/python27' 16:10:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:49 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:51 INFO - Installing pip.................done. 16:10:51 INFO - Return code: 0 16:10:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:51 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')]} 16:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:51 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')]} 16:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:51 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:10:51 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 16:10:51 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 16:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:51 INFO - 'HOME': '/Users/cltbld', 16:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:51 INFO - 'LOGNAME': 'cltbld', 16:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:51 INFO - 'MOZ_NO_REMOTE': '1', 16:10:51 INFO - 'NO_EM_RESTART': '1', 16:10:51 INFO - 'PAGER': '/bin/cat', 16:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:51 INFO - 'PWD': '/builds/slave/test', 16:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:51 INFO - 'SHELL': '/bin/bash', 16:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:10:51 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:51 INFO - 'USER': 'cltbld', 16:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:51 INFO - 'XPC_FLAGS': '0x0', 16:10:51 INFO - 'XPC_SERVICE_NAME': '0', 16:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:52 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 16:10:52 INFO - Cleaning up... 16:10:52 INFO - Return code: 0 16:10:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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', '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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:10: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 psutil>=3.1.1 16:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:52 INFO - 'HOME': '/Users/cltbld', 16:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:52 INFO - 'LOGNAME': 'cltbld', 16:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:52 INFO - 'MOZ_NO_REMOTE': '1', 16:10:52 INFO - 'NO_EM_RESTART': '1', 16:10:52 INFO - 'PAGER': '/bin/cat', 16:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:52 INFO - 'PWD': '/builds/slave/test', 16:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:52 INFO - 'SHELL': '/bin/bash', 16:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:10:52 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:52 INFO - 'USER': 'cltbld', 16:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:52 INFO - 'XPC_FLAGS': '0x0', 16:10:52 INFO - 'XPC_SERVICE_NAME': '0', 16:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:52 INFO - Downloading/unpacking psutil>=3.1.1 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:55 INFO - Installing collected packages: psutil 16:10:55 INFO - Running setup.py install for psutil 16:10:55 INFO - building 'psutil._psutil_osx' extension 16:10:55 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 16:10:56 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 16:10:56 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 16:10:56 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 16:10:56 INFO - building 'psutil._psutil_posix' extension 16:10:56 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 16:10:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:56 INFO - ^ 16:10:56 INFO - 1 warning generated. 16:10:56 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 16:10:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:56 INFO - Successfully installed psutil 16:10:56 INFO - Cleaning up... 16:10:56 INFO - Return code: 0 16:10:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:10: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:10: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 mozsystemmonitor==0.3 16:10:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:10:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:56 INFO - 'HOME': '/Users/cltbld', 16:10:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:56 INFO - 'LOGNAME': 'cltbld', 16:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:56 INFO - 'MOZ_NO_REMOTE': '1', 16:10:56 INFO - 'NO_EM_RESTART': '1', 16:10:56 INFO - 'PAGER': '/bin/cat', 16:10:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:56 INFO - 'PWD': '/builds/slave/test', 16:10:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:56 INFO - 'SHELL': '/bin/bash', 16:10:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:10:56 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:10:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:56 INFO - 'USER': 'cltbld', 16:10:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:56 INFO - 'XPC_FLAGS': '0x0', 16:10:56 INFO - 'XPC_SERVICE_NAME': '0', 16:10:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:59 INFO - Installing collected packages: mozsystemmonitor 16:10:59 INFO - Running setup.py install for mozsystemmonitor 16:10:59 INFO - Successfully installed mozsystemmonitor 16:10:59 INFO - Cleaning up... 16:10:59 INFO - Return code: 0 16:10:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:59 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')]} 16:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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')]} 16:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:59 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:10:59 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 16:10:59 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 16:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:59 INFO - 'HOME': '/Users/cltbld', 16:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:59 INFO - 'LOGNAME': 'cltbld', 16:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:59 INFO - 'MOZ_NO_REMOTE': '1', 16:10:59 INFO - 'NO_EM_RESTART': '1', 16:10:59 INFO - 'PAGER': '/bin/cat', 16:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:59 INFO - 'PWD': '/builds/slave/test', 16:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:59 INFO - 'SHELL': '/bin/bash', 16:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:10:59 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:59 INFO - 'USER': 'cltbld', 16:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:59 INFO - 'XPC_FLAGS': '0x0', 16:10:59 INFO - 'XPC_SERVICE_NAME': '0', 16:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:00 INFO - Downloading/unpacking jsonschema==2.5.1 16:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:11:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:11:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:11:02 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 16:11:02 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 16:11:02 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 16:11:02 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 16:11:02 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 16:11:02 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 16:11:02 INFO - Downloading functools32-3.2.3-2.tar.gz 16:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:11:02 INFO - warning: no files found matching '*.txt' 16:11:02 INFO - no previously-included directories found matching 'build' 16:11:02 INFO - no previously-included directories found matching 'dist' 16:11:02 INFO - no previously-included directories found matching '.git*' 16:11:02 INFO - Installing collected packages: jsonschema, functools32 16:11:03 INFO - Running setup.py install for functools32 16:11:03 INFO - warning: no files found matching '*.txt' 16:11:03 INFO - no previously-included directories found matching 'build' 16:11:03 INFO - no previously-included directories found matching 'dist' 16:11:03 INFO - no previously-included directories found matching '.git*' 16:11:03 INFO - Successfully installed jsonschema functools32 16:11:03 INFO - Cleaning up... 16:11:03 INFO - Return code: 0 16:11:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:11:03 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')]} 16:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:03 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')]} 16:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:03 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:03 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 16:11:03 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 16:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:03 INFO - 'HOME': '/Users/cltbld', 16:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:03 INFO - 'LOGNAME': 'cltbld', 16:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:03 INFO - 'MOZ_NO_REMOTE': '1', 16:11:03 INFO - 'NO_EM_RESTART': '1', 16:11:03 INFO - 'PAGER': '/bin/cat', 16:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:03 INFO - 'PWD': '/builds/slave/test', 16:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:03 INFO - 'SHELL': '/bin/bash', 16:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:03 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:03 INFO - 'USER': 'cltbld', 16:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:03 INFO - 'XPC_FLAGS': '0x0', 16:11:03 INFO - 'XPC_SERVICE_NAME': '0', 16:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:11:03 INFO - Cleaning up... 16:11:03 INFO - Return code: 0 16:11:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:11:03 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')]} 16:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:03 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')]} 16:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:03 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:03 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 16:11:03 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 16:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:03 INFO - 'HOME': '/Users/cltbld', 16:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:03 INFO - 'LOGNAME': 'cltbld', 16:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:03 INFO - 'MOZ_NO_REMOTE': '1', 16:11:03 INFO - 'NO_EM_RESTART': '1', 16:11:03 INFO - 'PAGER': '/bin/cat', 16:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:03 INFO - 'PWD': '/builds/slave/test', 16:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:03 INFO - 'SHELL': '/bin/bash', 16:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:03 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:03 INFO - 'USER': 'cltbld', 16:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:03 INFO - 'XPC_FLAGS': '0x0', 16:11:03 INFO - 'XPC_SERVICE_NAME': '0', 16:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:03 INFO - Downloading/unpacking blobuploader==1.2.4 16:11:03 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 16:11:03 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 16:11:03 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 16:11:03 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 16:11:03 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 16:11:03 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 16:11:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:11:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:11:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:06 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 16:11:07 INFO - Downloading docopt-0.6.1.tar.gz 16:11:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:11:07 INFO - Installing collected packages: blobuploader, requests, docopt 16:11:07 INFO - Running setup.py install for blobuploader 16:11:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:11:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:11:07 INFO - Running setup.py install for requests 16:11:08 INFO - Running setup.py install for docopt 16:11:08 INFO - Successfully installed blobuploader requests docopt 16:11:08 INFO - Cleaning up... 16:11:08 INFO - Return code: 0 16:11:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:08 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')]} 16:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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')]} 16:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:08 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:08 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 16:11:08 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 16:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:08 INFO - 'HOME': '/Users/cltbld', 16:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:08 INFO - 'LOGNAME': 'cltbld', 16:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:08 INFO - 'MOZ_NO_REMOTE': '1', 16:11:08 INFO - 'NO_EM_RESTART': '1', 16:11:08 INFO - 'PAGER': '/bin/cat', 16:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:08 INFO - 'PWD': '/builds/slave/test', 16:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:08 INFO - 'SHELL': '/bin/bash', 16:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:08 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:08 INFO - 'USER': 'cltbld', 16:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:08 INFO - 'XPC_FLAGS': '0x0', 16:11:08 INFO - 'XPC_SERVICE_NAME': '0', 16:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:08 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-8tIKW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:08 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-f6fmYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:08 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-FSvEeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:08 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-A1pldD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-37Svur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-E7eUoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-fCvHos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-vjj1om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MUcTQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-QQyAXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-DRC5qI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-cmTSCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-SbTxCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:09 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NxR_r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:10 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-H9vXdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:10 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-APyd8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:10 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-3fxTGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:11:10 INFO - Running setup.py install for manifestparser 16:11:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:11:10 INFO - Running setup.py install for mozcrash 16:11:10 INFO - Running setup.py install for mozdebug 16:11:10 INFO - Running setup.py install for mozdevice 16:11:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:11:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Running setup.py install for mozfile 16:11:11 INFO - Running setup.py install for mozhttpd 16:11:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Running setup.py install for mozinfo 16:11:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Running setup.py install for mozInstall 16:11:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Running setup.py install for mozleak 16:11:11 INFO - Running setup.py install for mozlog 16:11:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:11:11 INFO - Running setup.py install for moznetwork 16:11:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Running setup.py install for mozprocess 16:11:12 INFO - Running setup.py install for mozprofile 16:11:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Running setup.py install for mozrunner 16:11:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Running setup.py install for mozscreenshot 16:11:12 INFO - Running setup.py install for moztest 16:11:12 INFO - Running setup.py install for mozversion 16:11:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:11:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:11:12 INFO - Cleaning up... 16:11:13 INFO - Return code: 0 16:11:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:13 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')]} 16:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:13 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')]} 16:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:13 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:13 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 16:11:13 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 16:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:13 INFO - 'HOME': '/Users/cltbld', 16:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:13 INFO - 'LOGNAME': 'cltbld', 16:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:13 INFO - 'MOZ_NO_REMOTE': '1', 16:11:13 INFO - 'NO_EM_RESTART': '1', 16:11:13 INFO - 'PAGER': '/bin/cat', 16:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:13 INFO - 'PWD': '/builds/slave/test', 16:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:13 INFO - 'SHELL': '/bin/bash', 16:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:13 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:13 INFO - 'USER': 'cltbld', 16:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:13 INFO - 'XPC_FLAGS': '0x0', 16:11:13 INFO - 'XPC_SERVICE_NAME': '0', 16:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ZkZ3xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:11:13 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-73oP4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-vXOWFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:11:13 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ueYseA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BOBuf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:11:13 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-LNXLq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:11:13 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)) 16:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:11:13 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-J2_i7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-66RHYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-QRSaHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-6tYhLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:11:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MSXR1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-3YyRyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:11:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-RX2Vjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-R6JicQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:11:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-N3uPih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:11:14 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)) 16:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:11:14 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-wslJdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 16:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:11:15 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Dv6iMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:11:15 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)) 16:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:11:15 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)) 16:11:15 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:15 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:11:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:11:15 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 16:11:15 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 16:11:15 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 16:11:15 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 16:11:15 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 16:11:15 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 16:11:17 INFO - Downloading blessings-1.6.tar.gz 16:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:11:17 INFO - Installing collected packages: blessings 16:11:17 INFO - Running setup.py install for blessings 16:11:17 INFO - Successfully installed blessings 16:11:17 INFO - Cleaning up... 16:11:17 INFO - Return code: 0 16:11:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:11:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:18 INFO - Reading from file tmpfile_stdout 16:11:18 INFO - Current package versions: 16:11:18 INFO - blessings == 1.6 16:11:18 INFO - blobuploader == 1.2.4 16:11:18 INFO - docopt == 0.6.1 16:11:18 INFO - functools32 == 3.2.3-2 16:11:18 INFO - jsonschema == 2.5.1 16:11:18 INFO - manifestparser == 1.1 16:11:18 INFO - mozInstall == 1.12 16:11:18 INFO - mozcrash == 0.17 16:11:18 INFO - mozdebug == 0.1 16:11:18 INFO - mozdevice == 0.48 16:11:18 INFO - mozfile == 1.2 16:11:18 INFO - mozhttpd == 0.7 16:11:18 INFO - mozinfo == 0.9 16:11:18 INFO - mozleak == 0.1 16:11:18 INFO - mozlog == 3.3 16:11:18 INFO - moznetwork == 0.27 16:11:18 INFO - mozprocess == 0.23 16:11:18 INFO - mozprofile == 0.28 16:11:18 INFO - mozrunner == 6.12 16:11:18 INFO - mozscreenshot == 0.1 16:11:18 INFO - mozsystemmonitor == 0.3 16:11:18 INFO - moztest == 0.7 16:11:18 INFO - mozversion == 1.4 16:11:18 INFO - psutil == 3.1.1 16:11:18 INFO - requests == 1.2.3 16:11:18 INFO - wsgiref == 0.1.2 16:11:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:18 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 16:11:18 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 16:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:18 INFO - 'HOME': '/Users/cltbld', 16:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:18 INFO - 'LOGNAME': 'cltbld', 16:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:18 INFO - 'MOZ_NO_REMOTE': '1', 16:11:18 INFO - 'NO_EM_RESTART': '1', 16:11:18 INFO - 'PAGER': '/bin/cat', 16:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:18 INFO - 'PWD': '/builds/slave/test', 16:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:18 INFO - 'SHELL': '/bin/bash', 16:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:18 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:18 INFO - 'USER': 'cltbld', 16:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:18 INFO - 'XPC_FLAGS': '0x0', 16:11:18 INFO - 'XPC_SERVICE_NAME': '0', 16:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 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)) 16:11:18 INFO - Cleaning up... 16:11:18 INFO - Return code: 0 16:11:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:11:18 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 0x110511a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105a6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9561c5f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, '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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 16:11:18 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 16:11:18 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 16:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:18 INFO - 'HOME': '/Users/cltbld', 16:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:18 INFO - 'LOGNAME': 'cltbld', 16:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:18 INFO - 'MOZ_NO_REMOTE': '1', 16:11:18 INFO - 'NO_EM_RESTART': '1', 16:11:18 INFO - 'PAGER': '/bin/cat', 16:11:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:18 INFO - 'PWD': '/builds/slave/test', 16:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:18 INFO - 'SHELL': '/bin/bash', 16:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:18 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:18 INFO - 'USER': 'cltbld', 16:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:18 INFO - 'XPC_FLAGS': '0x0', 16:11:18 INFO - 'XPC_SERVICE_NAME': '0', 16:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:11:18 INFO - Cleaning up... 16:11:18 INFO - Return code: 0 16:11:18 INFO - Running post-action listener: _resource_record_post_action 16:11:18 INFO - Running post-action listener: _start_resource_monitoring 16:11:18 INFO - Starting resource monitoring. 16:11:18 INFO - [mozharness: 2016-08-11 23:11:18.701778Z] Finished create-virtualenv step (success) 16:11:18 INFO - [mozharness: 2016-08-11 23:11:18.702794Z] Running install step. 16:11:18 INFO - Running pre-action listener: _resource_record_pre_action 16:11:18 INFO - Running main action method: install 16:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:11:18 INFO - Reading from file tmpfile_stdout 16:11:18 INFO - Detecting whether we're running mozinstall >=1.0... 16:11:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:11:19 INFO - Reading from file tmpfile_stdout 16:11:19 INFO - Output received: 16:11:19 INFO - Usage: mozinstall [options] installer 16:11:19 INFO - Options: 16:11:19 INFO - -h, --help show this help message and exit 16:11:19 INFO - -d DEST, --destination=DEST 16:11:19 INFO - Directory to install application into. [default: 16:11:19 INFO - "/builds/slave/test"] 16:11:19 INFO - --app=APP Application being installed. [default: firefox] 16:11:19 INFO - mkdir: /builds/slave/test/build/application 16:11:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:11:53 INFO - Reading from file tmpfile_stdout 16:11:53 INFO - Output received: 16:11:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:11:53 INFO - Running post-action listener: _resource_record_post_action 16:11:53 INFO - [mozharness: 2016-08-11 23:11:53.017433Z] Finished install step (success) 16:11:53 INFO - [mozharness: 2016-08-11 23:11:53.017568Z] Running run-tests step. 16:11:53 INFO - Running pre-action listener: _resource_record_pre_action 16:11:53 INFO - Running main action method: run_tests 16:11:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:53 INFO - 2016-08-11 16:11:53.032 screenresolution[2301:19502] starting screenresolution argv=screenresolution get 16:11:53 INFO - 2016-08-11 16:11:53.035 screenresolution[2301:19502] Display 0: 1600x1200x32@60 16:11:53 INFO - 2016-08-11 16:11:53.041 screenresolution[2302:19504] starting screenresolution argv=screenresolution list 16:11:53 INFO - Available Modes on Display 0 16:11:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:53 INFO - Intel Iris: 16:11:53 INFO - Chipset Model: Intel Iris 16:11:53 INFO - Type: GPU 16:11:53 INFO - Bus: Built-In 16:11:53 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:53 INFO - Vendor: Intel (0x8086) 16:11:53 INFO - Device ID: 0x0a2e 16:11:53 INFO - Revision ID: 0x0009 16:11:53 INFO - Displays: 16:11:53 INFO - Display: 16:11:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:53 INFO - Display Serial Number: 1600x1200 60 16:11:53 INFO - Main Display: Yes 16:11:53 INFO - Mirror: Off 16:11:53 INFO - Online: Yes 16:11:53 INFO - Rotation: Supported 16:11:53 INFO - Return code: 0 16:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:53 INFO - Python 2.7.3 16:11:53 INFO - Return code: 0 16:11:53 INFO - grabbing minidump binary from tooltool 16:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:53 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 0x110475d30>, '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 0x1101dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11046db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:53 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 16:11:53 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 16:11:53 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 16:11:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:53 INFO - Return code: 0 16:11:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K7WkVdTJRU/Render', 16:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:53 INFO - 'HOME': '/Users/cltbld', 16:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:53 INFO - 'LOGNAME': 'cltbld', 16:11:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:53 INFO - 'MOZ_NO_REMOTE': '1', 16:11:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:53 INFO - 'NO_EM_RESTART': '1', 16:11:53 INFO - 'PAGER': '/bin/cat', 16:11:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:53 INFO - 'PWD': '/builds/slave/test', 16:11:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:53 INFO - 'SHELL': '/bin/bash', 16:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pwlIVhwzCC/Listeners', 16:11:53 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 16:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:53 INFO - 'USER': 'cltbld', 16:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:53 INFO - 'XPC_FLAGS': '0x0', 16:11:53 INFO - 'XPC_SERVICE_NAME': '0', 16:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:11:53 INFO - mozversion application_buildid: 20160811135022 16:11:53 INFO - mozversion application_changeset: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 16:11:53 INFO - mozversion application_display_name: Nightly 16:11:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:53 INFO - mozversion application_name: Firefox 16:11:53 INFO - mozversion application_remotingname: firefox 16:11:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:11:53 INFO - mozversion application_vendor: Mozilla 16:11:53 INFO - mozversion application_version: 51.0a1 16:11:53 INFO - mozversion platform_buildid: 20160811135022 16:11:53 INFO - mozversion platform_changeset: 233ab21b64b5d5e9f2f16ea2d4cfb4c8b293c5c4 16:11:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:11:53 INFO - mozversion platform_version: 51.0a1 16:11:53 INFO - using testdate: 1470957113 16:11:53 INFO - actual date: 1470957113 16:11:53 INFO - starting webserver on 'localhost:51691' 16:11:53 INFO - SUITE-START | Running 2 tests 16:11:53 INFO - TEST-START | dromaeo_css 16:11:53 INFO - operating with platform_type : mac_ 16:11:53 INFO - Initialising browser for dromaeo_css test... 16:11:53 INFO - TEST-INFO | started process 2312 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpWgkQxE/profile http://localhost:51691/getInfo.html) 16:11:56 INFO - PROCESS | 2312 | __metrics Screen width/height:1600/1200 16:11:56 INFO - PROCESS | 2312 | colorDepth:24 16:11:56 INFO - PROCESS | 2312 | Browser inner width/height: 1024/689 16:11:56 INFO - PROCESS | 2312 | __metrics 16:11:56 INFO - PROCESS | 2312 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:11:56 INFO - PROCESS | 2312 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:57 INFO - TEST-INFO | 2312: exit 0 16:11:57 INFO - Browser initialized. 16:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:57 INFO - TEST-INFO | started process 2314 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpWgkQxE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:12:08 INFO - PROCESS | 2314 | RSS: Main: 243486720 16:12:08 INFO - PROCESS | 2314 | 16:13:00 INFO - PROCESS | 2314 | 1470957180363 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:15:12 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51691/tests/dromaeo/cssquery-ext.html) 16:15:13 INFO - PROCESS | 2314 | RSS: Main: 392388608 16:15:13 INFO - PROCESS | 2314 | 16:18:16 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-ext.html (next: http://localhost:51691/tests/dromaeo/cssquery-jquery.html) 16:18:17 INFO - PROCESS | 2314 | RSS: Main: 390459392 16:18:17 INFO - PROCESS | 2314 | 16:21:19 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51691/tests/dromaeo/cssquery-mootools.html) 16:21:20 INFO - PROCESS | 2314 | RSS: Main: 360894464 16:21:20 INFO - PROCESS | 2314 | 16:24:22 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51691/tests/dromaeo/cssquery-prototype.html) 16:24:23 INFO - PROCESS | 2314 | RSS: Main: 347635712 16:24:23 INFO - PROCESS | 2314 | 16:27:26 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51691/tests/dromaeo/cssquery-yui.html) 16:27:27 INFO - PROCESS | 2314 | RSS: Main: 346062848 16:27:27 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:51691/tests/dromaeo/cssquery-yui.html (next: http://localhost:51691/tests/dromaeo/cssquery-dojo.html) 16:30:30 INFO - PROCESS | 2314 | RSS: Main: 530014208 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | __start_tp_report 16:30:30 INFO - PROCESS | 2314 | _x_x_mozilla_page_load 16:30:30 INFO - PROCESS | 2314 | _x_x_mozilla_page_load_details 16:30:30 INFO - PROCESS | 2314 | |i|pagename|runs| 16:30:30 INFO - PROCESS | 2314 | |0;dojo.html;3362.8486718097683;3364.7812892162015;3383.3064221834543;3405.965940340599;3451.5512983312183;5292.80911794846;5563.36021357544;5667.9999999999945;5755.395683453235;5769.971150144249;5288.8413347599535;5780.0751879699255;5811.157382179582;5838.474537291643;5875.7062146892695;5147.433782283946;5196.350456192976;5212.374515058179;5247.711375874325;5281.9207711884355;4443.200223959686;4850;5338.893222135555;5356.571474282048;5410.972945135269;5091.414487333967;5279.947200527984;5285.603579731516;5304.151335786256;5323.760430780605;4317.676174286909;5139.357580302462;5185.325907632021;5188.688678679291;5194.792208311663;5080.8221712240265;5124.538791508745;5168.121419358718;5172.301739265189;5179.378474583036;5579.944200557983;5593.272874526286;5599.356074051496;5616;5616.045272303718;6100.722874713385;6111.724972376253;6113.358097399798;6121.081837724332;6130.887362651042;6252.218472690914;6299.433051025429;6348.365163483661;6348.682565871734;6353.777617783356;5822.097574875901;5827.271591051119;5877.4122587740885;5885.911711324334;5896.528277737771;6223.284322302902;6233.625982441068;6285.497160227171;6312.495000399957;6315.400036996478;6143.72682107623;6198.6280823150755;6224.719887605069;6226.719797609118;6232.158658581034;6302.590331628429;6302.779402720883;6314.968425157845;6318.905216421757;6338;6079.604825686316;6109.443041498436;6267.65527895961;6274.058891166362;6284.183056202664;6111.318268824492;6149.016157414792;6164.907526387108;6172.382761723792;6238.65687387189;4910.779014944336;4920.434150072788;4933.45731969483;4936.925946110811;4944.579710724557;4375.212461756851;4437.245668236407;4441.067375851043;4452.532484089189;4492.213862574036;5650.463205995424;5671.064274394678;5685.687287199161;5713.22871412354;5739.913901291484;6254.8123556293385;6292.4966002719675;6296.905546416808;6299.527535434769;6300;5748.511376533042;5756.971215143897;5762.2221000164445;5769.884602308014;5773.480386765212;5343.519083282523;5397.163439666858;5416.539594134542;5456.781728730885;5467.316585426822;6339;6370;6370.90443643355;6372.044193371032;6380;6303.275123360872;6304.275008374096;6304.621722696653;6314.084457753692;6318.778842740482;6190.566660333913;6205.162303089025;6206.06908963659;6269.12232287471;6289.7169627365965;6231.2522497298805;6232.657203853832;6240.469560087444;6251.031090181029;6251.562390632612;6275.874482510416;6289.27673317556;6292.842678933063;6303.1175635412;6315.684215789284;5934.821955341346;5956.612820166675;6094.939050609614;6121.42248870793;6172.351903050115;6061.515078793598;6142.344497607763;6175.9073613894925;6201.906971395342;6213.161223234986;6019.036954087325;6045.365236650088;6051.515878729779;6058.979634433519;6103.206583144163;5894.675792831264;6023.397660233942;6104;6175.104609831459;6182.103594978793;6009.429104235091;6023.819285421444;6055.182550355822;6077.149199112039;6121.112438696275 16:30:30 INFO - PROCESS | 2314 | |1;ext.html;524.4336116993646;525.1702310349647;535.3468768102915;541.2746919128367;559.6558116758195;30591.082267531983;40446.59553404465;41383.79308103463;41486.585134148685;43456;42782.57217427825;42796.78601606988;42847.92880177989;42888.57111428885;43015.13969720604;9711.417314961107;9883.258755593359;9898.109170174683;9901.801963960717;9902.10881020708;8752.256058235025;8954.641814327419;11808.940955295211;11984.161108722394;11984.400779960966;11936.16446848721;11996.940015299911;11997.280163190193;12010.219335743192;12122.575709850113;11981.880181198207;12021.519139234508;12023.21849079807;12049.638510844688;12052.819207711847;11848.881511184863;11932;11934.940325298405;11941.044716422752;11952;39615.80192099021;39889.80055099735;39893.00267493307;40043.19913601741;40113.79943100295;3131.2171957010746;3416.982915085409;3625.29306785177;3698.9075273118387;3912.1197730510694;31009.37981240385;31630.683693163235;32457.350852983043;32593;32609.836950815094;3165.2245199925937;3272.721818645418;3327.3121026736194;3349.9832500837338;3386.6105397879314;464764;470821;472246;473186.99999999657;473571;56142.719286403306;56402.71798640981;56516.717416412655;56526.43473565294;56633.433665662815;62539;62653.68673156605;62753.058704119474;62804.37195628077;62891.37108628855;16152;16424;16424.589385265226;16464.753028704577;16465.423710170086;11604.6518604442;11900.226485278428;12830.422630981628;12874.935625321814;12896.871031289755;12293.815592766116;12408.75182496363;15025.248737563079;15028.474003409829;16351.672966540602;5967.880642387128;6990.196127445409;7037.366637002694;7054.6825392857445;7055.753048643272;737.325347307208;913.3332667153015;969.743018100204;1053.262716098734;1058.714147180257;28882.566761498594;29290.414191716045;29411.47211555676;29479.26301842428;29646.738858725523;977.3451787302524;987.3680844259679;1049.2969710294121;1055.1664185293685;1138.8519492465925;14542.636434089232;15651.608709782118;15718.921405392899;15837.762433563506;15857.524274271791;188878.1112188888;190606;190645;190969;901590;57565;58461;58654;62081.37918620846;62470.06534379227;1365.0853927868352;1394.6931675031476;1419.7018626088636;1423.188782394067;1430.513625367401;30436.543451847796;30961.690383096782;31096.844515777277;31704.682953170173;31920.680793191772;50861.74569127131;51593;51603.74198128985;51669.7416512915;51696;510991;512592;512999;513029;513293;26527;26898.731012689623;26904.78928448264;39811.80094099511;40202;47956.04087918152;48732;48968.26547601718;49512.75243623759;49634.255486167014;36858.63141368552;38422.80788596039;39781;40515;40684;34321;34719.305613888086;35886.820565897004;39304;40790;38101.809490952364;39331.6066839328;39349.409758853064;40426.59573404228;41458.58541414668;33684.66315336815;34889.65110348864;35853.64146358503;36002.8199858999;36080.63919360773;21871.781282186974;25441.618375724007;25577.744222557536;25868.35329116787;26613.334666633487 16:30:30 INFO - PROCESS | 2314 | |2;jquery.html;357.63878482732434;373.8149615940108;380.16553664705964;382.8755654412316;403.70327809060336;15905.204739763009;15912;20566.383008509732;21203.257885973955;21293.467663308405;20297.39107826764;20551.486212844688;20920.686189707165;21580.352589422302;21751.238706645272;5834.183214349973;5860.384659610732;5955.523558115362;6034.306054803688;6174.567780255384;5963.194968679238;6060.636361818305;6065.909011364833;6067.24159480065;6080.847978800543;4163.895902602444;5048.268001139832;6977.686004129826;6986.755463558777;7081.858362832741;6607.93392066078;6679.198496180489;7044.22513523512;7045.330693584101;7046.436285097206;5830.941690583082;5840.3575606683235;5843.415658434165;5843.8539036524;5846.093855452412;23500.177493787636;25393.49213015748;27109.05118320869;27211.863940680367;27598.44803103947;2384.5826980278557;2437.3784684905413;2601.713811480736;2723.850188239646;3151.4012337656;14497;14654.047486913423;15059.322330495153;15124.168170750605;15232.695346093127;1879.5864909719976;1880.8965506897116;1934.941951741436;1944.5916357564786;1981.3857704111626;112525;115453;117963.41018294939;121851.28528150235;122933.99999999911;9037.548122593975;10913.126949843994;10980.450977451095;11072.667819965414;11365.488553015135;14060.507882224074;14081.929590351983;14090.859091408955;14091.224982626054;14107.858921410654;8623.698170564001;8790.120987901159;8799.648014079494;8822.823543529223;8937.553122343857;8827.337949653802;8844.867326990101;8871.379003469696;9011;9061.728148155566;6697.330266973361;6771.4111520894;6815.931840681629;6823.23658918139;6960.477964152708;1806.3858288182082;1851.3890416162635;1853.7960824309316;1859.9628007439776;1862.2085613614431;908.3505293715026;912.245020039477;929.9116583924516;944.976375590602;1014.7361685961703;21562.460938476663;22946.196883109344;22969.655455168187;22996.88501557515;23003.079876805074;847.7795773099039;940.722486866376;942.2885721280389;968.3657204531044;1032.912202462784;11376.54493820238;11394.917482839117;11897.881021189678;12031.09766767496;12099.818502722466;598397;605644;605928;616719.9164004241;617143;118248;122296.77703223031;122716;126249;128364;984.9162821160281;998.7203582996627;1037.3983089807894;1037.5071840875523;1037.6316407675317;18459.353922612645;19334.516637084187;19402.708959365904;19418.805811942264;19432.2227110914;52479.73760131175;53041.469585305196;54497;55429;55988;77560;78079.60960195163;82229.58885205536;85458;88502.55748721215;6519.5436319457185;8032.196780321921;8175.754727358189;8432.28325592307;8473.703420380503;10550.577976880839;11717.355545445082;11940.70148246266;13022.674433139247;13565.457381704622;9430.669926552539;11232.382218978035;11722.648320550397;11732.648020559396;11734.882651173379;11100.888991109985;11245.775084498428;11477.827832582685;11664.6500604982;11698.649040528799;11369.829452558053;11557.711057223636;12009.699757506132;12021;12030.518779248732;7963.562004089831;7991.081025681889;8325.750227493185;8436.704715334934;8446.239838414327;10329.225308101772;11301.09591232683;11655.417229138679;11743.706407339885;11785 16:30:30 INFO - PROCESS | 2314 | |3;mootools.html;1381.3231516556878;1519.6808670179266;1554.083090976324;1766.3729376071494;1919.47214516008;7765.417593680475;8263.173682631734;8513.318934485242;8717.476951382921;8731.476111433318;8659.567021648923;8710.73867783966;8715;8717.171868672465;8718.694845680402;2295.8392912496092;2324.3027091872455;2325.8604483730946;2326.0579465316573;2326.8254880883915;2300;2330.1844354475966;2350.9412264693433;2352.188494969241;2353.376355265856;2299.7815207555254;2318.026428899866;2320.2691152286975;2331.1374791327235;2342.051469154995;2281.5208806150727;2306.7312977862193;2309.353381053291;2316.8494047887;2320.3155069254612;2298.0577963035244;2305.8616483011074;2306.6194077977116;2308.0190918859585;2311.6301391777233;7968.282854543119;8260.256576908107;8669.869951950726;8752.956235218782;8836.204741573225;5120.103981803207;5124.846254612367;5286.709230992294;5286.97356513215;5289.867753306198;3549.982250088759;3616.2405894762132;3617.89146325608;3620.8913732588067;3631.2192878530977;1075.7471994081384;1081.329575663094;1102.1403305421784;1110.4558766204582;1111.8943700348455;11498.94250528742;11574.363410012445;11575.53697852085;11576.13179011569;11584.18910676253;4378.5183629800695;4409.316555933835;4547.249703798836;4623.4451865775645;4626.699264547794;7882.802929926797;9174.954125229331;9183.173514383738;9192.81614367709;9198.126178013077;5216.843494695166;5220.164773636276;5226.033183860922;5227.163653815372;5227.18978558324;4028.576999415078;4359.520452750195;4760.357351757469;4837.467878533358;4848.927266091011;3007.65411977621;3419.281671071504;3695.1685870678884;3705.073731567108;3826.4834247376803;940.7507010642186;1135.0919264588297;1139.7378602921374;1143.0798207443088;1154.96535103947;823.2250760912206;848.3637272045966;883.6067949762294;896.3725392225468;934.7336009237334;5455.890882182413;5496.608975095846;5500.4224556420995;5741.081426971665;5868.149118377897;886.5168483176684;917.5594316922501;934.9672761453453;945.583943065063;970.9805803884024;3615.8734444294323;3821.2739579480367;3952.6245006724316;3969.8412063517135;3971.6822654187595;9202.815943681222;10578.788424231472;11015.779684406427;11018.95319944604;11041.779164416666;9442.678025076495;10241.283110182365;10613.991670791262;10685.091767199876;10963.890361096446;8053.677852885936;8253.876191857127;8264.876026859602;8271.751847444466;8294.046184688717;5725.627834190765;5728.312602487728;5764.942350576441;5918.496927761016;6056.182415373868;8061.879071814044;10027.398356098658;10059.19526437898;10073.647422340184;10085;9282.535873206447;9606.567704453177;9768.218542516724;10146.289759717045;10173;7376.151742549676;7458.7250888477365;7562.886556701544;7563;7679.308862202429;8093.676252949816;8362.372822038493;8446.83106337882;8580.455518006818;8580.871286930576;6434.324395938546;7312.597807120659;7329.780106596811;7376.299251571092;7559.697612095674;7269.127704675261;7278.599677017814;7292.635368231461;7309;7321.487495875238;6898;6900.654967251718;6918.06606108189;6980.825479363057;6989.825254368681;4634.675572710013;4645.767711614473;4684.976575117103;4692.882677933079;4808.326834243278;4782.330473733749;4925.408950925912;4932.210846264724;4958.752062396938;5083.186690129562 16:30:30 INFO - PROCESS | 2314 | |4;prototype.html;296.59070482733824;308.3524598343479;309.01270440941204;313.7427309606124;316.0376653091337;4162.562930892254;4285.78571071446;4946.703197808134;5408.5943554233445;5417.431169727181;4620.122176786416;4844.61243100552;5230.5031022052935;5353.5984801139975;5363.785448582052;3844.9810800137984;3875.2830726315583;4015.6185162409524;4027.7986100694848;4142.192272506847;3347.548081009057;3995.3207954647633;4101.835926562934;4107.260693075245;4138.985948442636;3515.806630635314;4006.4791577095084;4010.4786377770847;4012.5586185519714;4028.756850735157;3583.122135076897;3608.8736894208578;3828.004718773111;3833.290841194374;3931.842726290945;2970.198046527447;3075.984620076885;3357.9758173756995;3494.5631796025496;3550.218951830619;4260.998665313648;4633.49031606523;4802.399700037496;5155.458676838915;5157.690538567698;4107.301758700998;4648.233671204921;4689.226277664181;4704.741239231841;4707.71753694776;5565.749541270652;5571.4428557144365;5581.972090139523;5589.841739417217;5687.687177205252;3748.9812550937345;3879.534455865314;3887.786171760552;3889.4943657324648;3927.2538217738825;4865.002674451729;5263.421023687391;5272.2355258487305;5942.5840191186635;6005.369436209223;3458.5849698036395;3930.6855451563806;3937.114149316438;3939.763614183158;3959.148783011609;3414.3683418567643;4001.7398869073418;4025.9194816103513;4035.6973227008093;4041.2119636671155;4047.534533528623;4090.7727681695374;4185.079282557833;4554.612857907115;4679.344891715163;4606.539346065367;5418.051840927901;5585.692786896679;5661.631993920382;5708.4862362387585;1334.472029526187;1362.2439546051837;1363.2638375277238;1363.799856126622;1364.8157498737544;796.7370767646663;826.2439867521271;830.5043344749112;840.911704271055;842.3471809347682;4361.236783562864;5124.231365295161;5239.397469291081;5252.474752524717;5311.229871668586;5020.665390593392;5064.518870707277;5752.33848107473;5758.62568933018;5777.873314703676;4588.6099681526775;4629.86110416688;4726.385569875894;4967.726775027409;5018.272350509155;2730.645016147888;2761.8757155928033;2777.777777777773;2954.478443601547;3013.3370658455106;5103.744812759347;5684.516816070598;5973.671448070398;5988.401159884064;6006.189164462741;5213.713245771519;5840.649561026352;5897.941020589739;5910.143029260733;5973.761049557969;5985.431384018511;6024.8853962017165;6025.276966764016;6030.7889223876955;6071.878562428727;4895.118878601886;4989.825356112664;5592.664440133442;5622.622457497939;5795.7828855940725;4700.506446823034;5064.164412871834;5712.542996560348;5767.336756273083;5775.537956963349;4777.805548612847;5797.391273916178;5950.5239580834295;6007.3992600739575;6026.548008899263;3763.981180094082;3967.3652215645357;3980.8805735827973;3992.540857801274;4003.4595329631293;5545.195946587803;5631.915521267266;5661.886762264814;5667.5465962722055;5677.716114194356;5013.498650134958;5521.365043020104;5529.336479622471;5600.747966341443;5616.6068375213345;5155.43290238081;5259.368875734936;5391.811286605107;5522.3649280331665;5550.306211723535;4535.6371490280035;5223.5559977402345;5491.533219676213;5513.421090785409;5521.199426083115;5366.946330536644;5505.697186654773;5527.309086364205;5641.971790141023;5667.3765885753355;5164.767585458589;5477.767502311948;5539.25220095282;5558;5560.638558493847 16:30:30 INFO - PROCESS | 2314 | |5;yui.html;661.3089321658863;684.7911387026954;692.698676075907;737.7565403416872;748.973785917493;15026.77459838102;16370;16385.67228655426;16400.753988690165;16404.67190656186;14826.406943722239;14985;14999.85000149998;15029.32368043438;15039.624009399771;672.6233309346757;690.1545606631886;691.9100516932791;693.1231991530718;820.6799348254185;626.9153664255335;684.0628339175312;686.4679873098339;798.4051881348404;831.4927893984665;878.1131057631774;887.6937456577502;893.9106089391074;895.136193573205;896.6368620708591;888.4580405952429;888.506878682331;889.9332550058772;892.219308105411;895.1183084661603;530.9017831701109;654.5450911616429;662;662.8574856405847;663.2041550139852;14553;14964;14973.850261497355;14992.475263365839;15034.323455444423;13742.244176570284;14034.92982535091;14211.644708882257;14230.501932432418;14357.353919073666;12738.74522509554;12992.415341309665;12995.220286782824;13121;13356.465741370333;4526.977365113186;4577.267637178069;4631.282151266559;4722.917854751955;4745.738984355859;116206.41896790462;117355;117361.41319293348;117718;117990.99999999914;10974.231803773659;11045.779084418267;11269.436528173559;11305;11393.145514086476;22426.663600045686;22432.775672243068;22441.663375049386;22464.32607021792;22886.313410597708;7861.174576669482;7880.605969701492;7882.132965373804;7952.2842944134145;8160.591970401456;4261.765602891871;4365.825366985285;4425.336199570026;4508.594226519629;4526.502084770673;3430.2110514581786;3539.681428671432;3548.1129717570607;3549.982250088733;3555.4311310190246;652.9020646902908;656.143732429175;661.6327937994363;672.707372293054;673.4444083631024;526.8472143078465;587.7825204674213;598.4882925098977;641.8812519683873;656.8456412743002;7665.655045522964;8427.789305267295;9182.540872956459;9383.718488445358;9968.056229035414;636.8726254748976;637.3912913167914;641.4739913271167;663.5193303314655;693.8196069022091;4314.41755363022;4321.330193819963;4498.762840218966;4501;4512.9774351128035;132092;133483;136257;139088;139972;112285.43857280661;124497;125141.37429312796;125506.37246813708;127668.36165819112;102691;107417.46291268493;110218;111530.88469115367;113552;6854.794356169323;7466.701331946661;8129.065157506962;8888.333374996771;9077.90922090797;48820;57527;59010.40989590049;59204;60670.393296066475;78726;81306.18693812986;87219.25561488607;87471;88540.5572972131;11537.769244615229;11894.643160705193;11995.64013079609;12609.243445393306;12680.809787853004;13099.476020959437;13252.370405395546;13360.866391335961;13644.181349119084;13804.240766757523;11957.76084478323;12446.190997585129;12491.812622810483;12744.980401567667;13023.869761302265;10896.727581810519;12159.046395970032;12442.253464792142;13576.864231357955;13894.861051389356;13418.93290533541;13430.597082087554;13454.394552244976;13784.724305513633;13786.724265514433;10669.35983840972;10962.561497540328;11309.604163854226;11324.433778311217;11492.540298388305;10464.372137671764;11153.944230278797;11224.60713875043;11448.484818183035;11458 16:30:30 INFO - PROCESS | 2314 | __end_tp_report 16:30:30 INFO - PROCESS | 2314 | __start_cc_report 16:30:30 INFO - PROCESS | 2314 | _x_x_mozilla_cycle_collect,1556 16:30:30 INFO - PROCESS | 2314 | __end_cc_report 16:30:30 INFO - PROCESS | 2314 | __startTimestamp1470958230820__endTimestamp 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | ------- Summary: start ------- 16:30:30 INFO - PROCESS | 2314 | Number of tests: 6 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#0] dojo.html Cycles:165 Average:5753.54 Median:6045.37 stddev:661.59 (10.9%) stddev-sans-first:636.48 16:30:30 INFO - PROCESS | 2314 | Values: 3362.8 3364.8 3383.3 3406.0 3451.6 5292.8 5563.4 5668.0 5755.4 5770.0 5288.8 5780.1 5811.2 5838.5 5875.7 5147.4 5196.4 5212.4 5247.7 5281.9 4443.2 4850.0 5338.9 5356.6 5411.0 5091.4 5279.9 5285.6 5304.2 5323.8 4317.7 5139.4 5185.3 5188.7 5194.8 5080.8 5124.5 5168.1 5172.3 5179.4 5579.9 5593.3 5599.4 5616.0 5616.0 6100.7 6111.7 6113.4 6121.1 6130.9 6252.2 6299.4 6348.4 6348.7 6353.8 5822.1 5827.3 5877.4 5885.9 5896.5 6223.3 6233.6 6285.5 6312.5 6315.4 6143.7 6198.6 6224.7 6226.7 6232.2 6302.6 6302.8 6315.0 6318.9 6338.0 6079.6 6109.4 6267.7 6274.1 6284.2 6111.3 6149.0 6164.9 6172.4 6238.7 4910.8 4920.4 4933.5 4936.9 4944.6 4375.2 4437.2 4441.1 4452.5 4492.2 5650.5 5671.1 5685.7 5713.2 5739.9 6254.8 6292.5 6296.9 6299.5 6300.0 5748.5 5757.0 5762.2 5769.9 5773.5 5343.5 5397.2 5416.5 5456.8 5467.3 6339.0 6370.0 6370.9 6372.0 6380.0 6303.3 6304.3 6304.6 6314.1 6318.8 6190.6 6205.2 6206.1 6269.1 6289.7 6231.3 6232.7 6240.5 6251.0 6251.6 6275.9 6289.3 6292.8 6303.1 6315.7 5934.8 5956.6 6094.9 6121.4 6172.4 6061.5 6142.3 6175.9 6201.9 6213.2 6019.0 6045.4 6051.5 6059.0 6103.2 5894.7 6023.4 6104.0 6175.1 6182.1 6009.4 6023.8 6055.2 6077.1 6121.1 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#1] ext.html Cycles:180 Average:59763.63 Median:19852.97 stddev:127298.40 (641.2%) stddev-sans-first:127577.81 16:30:30 INFO - PROCESS | 2314 | Values: 524.4 525.2 535.3 541.3 559.7 30591.1 40446.6 41383.8 41486.6 43456.0 42782.6 42796.8 42847.9 42888.6 43015.1 9711.4 9883.3 9898.1 9901.8 9902.1 8752.3 8954.6 11808.9 11984.2 11984.4 11936.2 11996.9 11997.3 12010.2 12122.6 11981.9 12021.5 12023.2 12049.6 12052.8 11848.9 11932.0 11934.9 11941.0 11952.0 39615.8 39889.8 39893.0 40043.2 40113.8 3131.2 3417.0 3625.3 3698.9 3912.1 31009.4 31630.7 32457.4 32593.0 32609.8 3165.2 3272.7 3327.3 3350.0 3386.6 464764.0 470821.0 472246.0 473187.0 473571.0 56142.7 56402.7 56516.7 56526.4 56633.4 62539.0 62653.7 62753.1 62804.4 62891.4 16152.0 16424.0 16424.6 16464.8 16465.4 11604.7 11900.2 12830.4 12874.9 12896.9 12293.8 12408.8 15025.2 15028.5 16351.7 5967.9 6990.2 7037.4 7054.7 7055.8 737.3 913.3 969.7 1053.3 1058.7 28882.6 29290.4 29411.5 29479.3 29646.7 977.3 987.4 1049.3 1055.2 1138.9 14542.6 15651.6 15718.9 15837.8 15857.5 188878.1 190606.0 190645.0 190969.0 901590.0 57565.0 58461.0 58654.0 62081.4 62470.1 1365.1 1394.7 1419.7 1423.2 1430.5 30436.5 30961.7 31096.8 31704.7 31920.7 50861.7 51593.0 51603.7 51669.7 51696.0 510991.0 512592.0 512999.0 513029.0 513293.0 26527.0 26898.7 26904.8 39811.8 40202.0 47956.0 48732.0 48968.3 49512.8 49634.3 36858.6 38422.8 39781.0 40515.0 40684.0 34321.0 34719.3 35886.8 39304.0 40790.0 38101.8 39331.6 39349.4 40426.6 41458.6 33684.7 34889.7 35853.6 36002.8 36080.6 21871.8 25441.6 25577.7 25868.4 26613.3 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#2] jquery.html Cycles:180 Average:35715.87 Median:13915.44 stddev:101387.67 (728.6%) stddev-sans-first:101637.33 16:30:30 INFO - PROCESS | 2314 | Values: 357.6 373.8 380.2 382.9 403.7 15905.2 15912.0 20566.4 21203.3 21293.5 20297.4 20551.5 20920.7 21580.4 21751.2 5834.2 5860.4 5955.5 6034.3 6174.6 5963.2 6060.6 6065.9 6067.2 6080.8 4163.9 5048.3 6977.7 6986.8 7081.9 6607.9 6679.2 7044.2 7045.3 7046.4 5830.9 5840.4 5843.4 5843.9 5846.1 23500.2 25393.5 27109.1 27211.9 27598.4 2384.6 2437.4 2601.7 2723.9 3151.4 14497.0 14654.0 15059.3 15124.2 15232.7 1879.6 1880.9 1934.9 1944.6 1981.4 112525.0 115453.0 117963.4 121851.3 122934.0 9037.5 10913.1 10980.5 11072.7 11365.5 14060.5 14081.9 14090.9 14091.2 14107.9 8623.7 8790.1 8799.6 8822.8 8937.6 8827.3 8844.9 8871.4 9011.0 9061.7 6697.3 6771.4 6815.9 6823.2 6960.5 1806.4 1851.4 1853.8 1860.0 1862.2 908.4 912.2 929.9 945.0 1014.7 21562.5 22946.2 22969.7 22996.9 23003.1 847.8 940.7 942.3 968.4 1032.9 11376.5 11394.9 11897.9 12031.1 12099.8 598397.0 605644.0 605928.0 616719.9 617143.0 118248.0 122296.8 122716.0 126249.0 128364.0 984.9 998.7 1037.4 1037.5 1037.6 18459.4 19334.5 19402.7 19418.8 19432.2 52479.7 53041.5 54497.0 55429.0 55988.0 77560.0 78079.6 82229.6 85458.0 88502.6 6519.5 8032.2 8175.8 8432.3 8473.7 10550.6 11717.4 11940.7 13022.7 13565.5 9430.7 11232.4 11722.6 11732.6 11734.9 11100.9 11245.8 11477.8 11664.7 11698.6 11369.8 11557.7 12009.7 12021.0 12030.5 7963.6 7991.1 8325.8 8436.7 8446.2 10329.2 11301.1 11655.4 11743.7 11785.0 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#3] mootools.html Cycles:180 Average:5581.97 Median:4945.48 stddev:3135.14 (63.4%) stddev-sans-first:3128.04 16:30:30 INFO - PROCESS | 2314 | Values: 1381.3 1519.7 1554.1 1766.4 1919.5 7765.4 8263.2 8513.3 8717.5 8731.5 8659.6 8710.7 8715.0 8717.2 8718.7 2295.8 2324.3 2325.9 2326.1 2326.8 2300.0 2330.2 2350.9 2352.2 2353.4 2299.8 2318.0 2320.3 2331.1 2342.1 2281.5 2306.7 2309.4 2316.8 2320.3 2298.1 2305.9 2306.6 2308.0 2311.6 7968.3 8260.3 8669.9 8753.0 8836.2 5120.1 5124.8 5286.7 5287.0 5289.9 3550.0 3616.2 3617.9 3620.9 3631.2 1075.7 1081.3 1102.1 1110.5 1111.9 11498.9 11574.4 11575.5 11576.1 11584.2 4378.5 4409.3 4547.2 4623.4 4626.7 7882.8 9175.0 9183.2 9192.8 9198.1 5216.8 5220.2 5226.0 5227.2 5227.2 4028.6 4359.5 4760.4 4837.5 4848.9 3007.7 3419.3 3695.2 3705.1 3826.5 940.8 1135.1 1139.7 1143.1 1155.0 823.2 848.4 883.6 896.4 934.7 5455.9 5496.6 5500.4 5741.1 5868.1 886.5 917.6 935.0 945.6 971.0 3615.9 3821.3 3952.6 3969.8 3971.7 9202.8 10578.8 11015.8 11019.0 11041.8 9442.7 10241.3 10614.0 10685.1 10963.9 8053.7 8253.9 8264.9 8271.8 8294.0 5725.6 5728.3 5764.9 5918.5 6056.2 8061.9 10027.4 10059.2 10073.6 10085.0 9282.5 9606.6 9768.2 10146.3 10173.0 7376.2 7458.7 7562.9 7563.0 7679.3 8093.7 8362.4 8446.8 8580.5 8580.9 6434.3 7312.6 7329.8 7376.3 7559.7 7269.1 7278.6 7292.6 7309.0 7321.5 6898.0 6900.7 6918.1 6980.8 6989.8 4634.7 4645.8 4685.0 4692.9 4808.3 4782.3 4925.4 4932.2 4958.8 5083.2 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#4] prototype.html Cycles:180 Average:4498.46 Median:5015.89 stddev:1395.24 (27.8%) stddev-sans-first:1363.05 16:30:30 INFO - PROCESS | 2314 | Values: 296.6 308.4 309.0 313.7 316.0 4162.6 4285.8 4946.7 5408.6 5417.4 4620.1 4844.6 5230.5 5353.6 5363.8 3845.0 3875.3 4015.6 4027.8 4142.2 3347.5 3995.3 4101.8 4107.3 4139.0 3515.8 4006.5 4010.5 4012.6 4028.8 3583.1 3608.9 3828.0 3833.3 3931.8 2970.2 3076.0 3358.0 3494.6 3550.2 4261.0 4633.5 4802.4 5155.5 5157.7 4107.3 4648.2 4689.2 4704.7 4707.7 5565.7 5571.4 5582.0 5589.8 5687.7 3749.0 3879.5 3887.8 3889.5 3927.3 4865.0 5263.4 5272.2 5942.6 6005.4 3458.6 3930.7 3937.1 3939.8 3959.1 3414.4 4001.7 4025.9 4035.7 4041.2 4047.5 4090.8 4185.1 4554.6 4679.3 4606.5 5418.1 5585.7 5661.6 5708.5 1334.5 1362.2 1363.3 1363.8 1364.8 796.7 826.2 830.5 840.9 842.3 4361.2 5124.2 5239.4 5252.5 5311.2 5020.7 5064.5 5752.3 5758.6 5777.9 4588.6 4629.9 4726.4 4967.7 5018.3 2730.6 2761.9 2777.8 2954.5 3013.3 5103.7 5684.5 5973.7 5988.4 6006.2 5213.7 5840.6 5897.9 5910.1 5973.8 5985.4 6024.9 6025.3 6030.8 6071.9 4895.1 4989.8 5592.7 5622.6 5795.8 4700.5 5064.2 5712.5 5767.3 5775.5 4777.8 5797.4 5950.5 6007.4 6026.5 3764.0 3967.4 3980.9 3992.5 4003.5 5545.2 5631.9 5661.9 5667.5 5677.7 5013.5 5521.4 5529.3 5600.7 5616.6 5155.4 5259.4 5391.8 5522.4 5550.3 4535.6 5223.6 5491.5 5513.4 5521.2 5366.9 5505.7 5527.3 5642.0 5667.4 5164.8 5477.8 5539.3 5558.0 5560.6 16:30:30 INFO - PROCESS | 2314 | 16:30:30 INFO - PROCESS | 2314 | [#5] yui.html Cycles:180 Average:24144.87 Median:3484.95 stddev:38089.00 (1093.0%) stddev-sans-first:38155.04 16:30:30 INFO - PROCESS | 2314 | Values: 661.3 684.8 692.7 737.8 749.0 15026.8 16370.0 16385.7 16400.8 16404.7 14826.4 14985.0 14999.9 15029.3 15039.6 672.6 690.2 691.9 693.1 820.7 626.9 684.1 686.5 798.4 831.5 878.1 887.7 893.9 895.1 896.6 888.5 888.5 889.9 892.2 895.1 530.9 654.5 662.0 662.9 663.2 14553.0 14964.0 14973.9 14992.5 15034.3 13742.2 14034.9 14211.6 14230.5 14357.4 12738.7 12992.4 12995.2 13121.0 13356.5 4527.0 4577.3 4631.3 4722.9 4745.7 116206.4 117355.0 117361.4 117718.0 117991.0 10974.2 11045.8 11269.4 11305.0 11393.1 22426.7 22432.8 22441.7 22464.3 22886.3 7861.2 7880.6 7882.1 7952.3 8160.6 4261.8 4365.8 4425.3 4508.6 4526.5 3430.2 3539.7 3548.1 3550.0 3555.4 652.9 656.1 661.6 672.7 673.4 526.8 587.8 598.5 641.9 656.8 7665.7 8427.8 9182.5 9383.7 9968.1 636.9 637.4 641.5 663.5 693.8 4314.4 4321.3 4498.8 4501.0 4513.0 132092.0 133483.0 136257.0 139088.0 139972.0 112285.4 124497.0 125141.4 125506.4 127668.4 102691.0 107417.5 110218.0 111530.9 113552.0 6854.8 7466.7 8129.1 8888.3 9077.9 48820.0 57527.0 59010.4 59204.0 60670.4 78726.0 81306.2 87219.3 87471.0 88540.6 11537.8 11894.6 11995.6 12609.2 12680.8 13099.5 13252.4 13360.9 13644.2 13804.2 11957.8 12446.2 12491.8 12745.0 13023.9 10896.7 12159.0 12442.3 13576.9 13894.9 13418.9 13430.6 13454.4 13784.7 13786.7 10669.4 10962.6 11309.6 11324.4 11492.5 10464.4 11153.9 11224.6 11448.5 11458.0 16:30:30 INFO - PROCESS | 2314 | -------- Summary: end -------- 16:30:30 INFO - PROCESS | 2314 | 16:30:31 INFO - TEST-INFO | 2314: exit 0 16:30:31 INFO - TEST-OK | dromaeo_css | took 1117851ms 16:30:31 INFO - TEST-START | kraken 16:30:31 INFO - operating with platform_type : mac_ 16:30:31 INFO - Initialising browser for kraken test... 16:30:31 INFO - TEST-INFO | started process 2317 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp1E5au1/profile http://localhost:51691/getInfo.html) 16:30:33 INFO - PROCESS | 2317 | __metrics Screen width/height:1600/1200 16:30:33 INFO - PROCESS | 2317 | colorDepth:24 16:30:33 INFO - PROCESS | 2317 | Browser inner width/height: 1024/689 16:30:33 INFO - PROCESS | 2317 | __metrics 16:30:33 INFO - PROCESS | 2317 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:30:33 INFO - PROCESS | 2317 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:30:34 INFO - TEST-INFO | 2317: exit 0 16:30:34 INFO - Browser initialized. 16:30:34 INFO - Running cycle 1/1 for kraken test... 16:30:34 INFO - TEST-INFO | started process 2318 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmp1E5au1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:46 INFO - PROCESS | 2318 | RSS: Main: 243437568 16:30:46 INFO - PROCESS | 2318 | 16:30:49 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51691/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:50 INFO - PROCESS | 2318 | RSS: Main: 292880384 16:30:50 INFO - PROCESS | 2318 | 16:30:52 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51691/tests/kraken/driver.html?testName=audio-dft) 16:30:53 INFO - PROCESS | 2318 | RSS: Main: 299646976 16:30:53 INFO - PROCESS | 2318 | 16:30:56 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51691/tests/kraken/driver.html?testName=audio-fft) 16:30:57 INFO - PROCESS | 2318 | RSS: Main: 394498048 16:30:57 INFO - PROCESS | 2318 | 16:30:59 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51691/tests/kraken/driver.html?testName=audio-oscillator) 16:31:00 INFO - PROCESS | 2318 | RSS: Main: 297185280 16:31:00 INFO - PROCESS | 2318 | 16:31:02 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51691/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:31:02 INFO - PROCESS | 2318 | RSS: Main: 302026752 16:31:02 INFO - PROCESS | 2318 | 16:31:05 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51691/tests/kraken/driver.html?testName=imaging-darkroom) 16:31:06 INFO - PROCESS | 2318 | RSS: Main: 338079744 16:31:06 INFO - PROCESS | 2318 | 16:31:09 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51691/tests/kraken/driver.html?testName=imaging-desaturate) 16:31:09 INFO - PROCESS | 2318 | RSS: Main: 339202048 16:31:09 INFO - PROCESS | 2318 | 16:31:12 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51691/tests/kraken/driver.html?testName=json-parse-financial) 16:31:13 INFO - PROCESS | 2318 | RSS: Main: 339161088 16:31:13 INFO - PROCESS | 2318 | 16:31:14 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51691/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:31:15 INFO - PROCESS | 2318 | RSS: Main: 313790464 16:31:15 INFO - PROCESS | 2318 | 16:31:17 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:31:17 INFO - PROCESS | 2318 | RSS: Main: 321196032 16:31:17 INFO - PROCESS | 2318 | 16:31:20 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:20 INFO - PROCESS | 2318 | RSS: Main: 325292032 16:31:20 INFO - PROCESS | 2318 | 16:31:23 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:24 INFO - PROCESS | 2318 | RSS: Main: 330190848 16:31:24 INFO - PROCESS | 2318 | 16:31:27 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:27 INFO - PROCESS | 2318 | RSS: Main: 332369920 16:31:27 INFO - PROCESS | 2318 | 16:31:29 INFO - PROCESS | 2318 | Cycle 1(1): loaded http://localhost:51691/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51691/tests/kraken/driver.html?testName=ai-astar) 16:31:30 INFO - PROCESS | 2318 | RSS: Main: 375529472 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | __start_tp_report 16:31:30 INFO - PROCESS | 2318 | _x_x_mozilla_page_load 16:31:30 INFO - PROCESS | 2318 | _x_x_mozilla_page_load_details 16:31:30 INFO - PROCESS | 2318 | |i|pagename|runs| 16:31:30 INFO - PROCESS | 2318 | |0;ai-astar;102;112;113;113;112;113;112;112;112;115 16:31:30 INFO - PROCESS | 2318 | |1;audio-beat-detection;144;108;123;164;107;105;127;107;139;106 16:31:30 INFO - PROCESS | 2318 | |2;audio-dft;181;185;185;182;184;186;180;184;181;185 16:31:30 INFO - PROCESS | 2318 | |3;audio-fft;80;57;58;88;61;56;64;65;83;56 16:31:30 INFO - PROCESS | 2318 | |4;audio-oscillator;86;69;100;70;75;68;97;70;68;103 16:31:30 INFO - PROCESS | 2318 | |5;imaging-gaussian-blur;88;87;89;86;88;85;89;86;89;86 16:31:30 INFO - PROCESS | 2318 | |6;imaging-darkroom;135;123;93;94;94;94;94;94;94;94 16:31:30 INFO - PROCESS | 2318 | |7;imaging-desaturate;85;85;83;90;123;83;85;84;83;83 16:31:30 INFO - PROCESS | 2318 | |8;json-parse-financial;54;52;53;54;55;53;52;53;53;54 16:31:30 INFO - PROCESS | 2318 | |9;json-stringify-tinderbox;68;68;83;78;76;70;72;75;73;72 16:31:30 INFO - PROCESS | 2318 | |10;stanford-crypto-aes;83;85;84;83;85;82;94;107;102;102 16:31:30 INFO - PROCESS | 2318 | |11;stanford-crypto-ccm;137;135;140;150;153;142;135;139;211;150 16:31:30 INFO - PROCESS | 2318 | |12;stanford-crypto-pbkdf2;167;167;167;166;166;165;165;165;162;164 16:31:30 INFO - PROCESS | 2318 | |13;stanford-crypto-sha256-iterative;66;68;67;67;67;65;85;87;85;80 16:31:30 INFO - PROCESS | 2318 | __end_tp_report 16:31:30 INFO - PROCESS | 2318 | __start_cc_report 16:31:30 INFO - PROCESS | 2318 | _x_x_mozilla_cycle_collect,2116 16:31:30 INFO - PROCESS | 2318 | __end_cc_report 16:31:30 INFO - PROCESS | 2318 | __startTimestamp1470958290064__endTimestamp 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | ------- Summary: start ------- 16:31:30 INFO - PROCESS | 2318 | Number of tests: 14 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.50 (3.1%) stddev-sans-first:1.00 16:31:30 INFO - PROCESS | 2318 | Values: 102.0 112.0 113.0 113.0 112.0 113.0 112.0 112.0 112.0 115.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:125.00 stddev:20.40 (16.3%) stddev-sans-first:20.17 16:31:30 INFO - PROCESS | 2318 | Values: 144.0 108.0 123.0 164.0 107.0 105.0 127.0 107.0 139.0 106.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:2.11 (1.1%) stddev-sans-first:2.07 16:31:30 INFO - PROCESS | 2318 | Values: 181.0 185.0 185.0 182.0 184.0 186.0 180.0 184.0 181.0 185.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#3] audio-fft Cycles:10 Average:66.80 Median:64.50 stddev:12.19 (18.9%) stddev-sans-first:11.96 16:31:30 INFO - PROCESS | 2318 | Values: 80.0 57.0 58.0 88.0 61.0 56.0 64.0 65.0 83.0 56.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:70.00 stddev:14.47 (20.7%) stddev-sans-first:15.22 16:31:30 INFO - PROCESS | 2318 | Values: 86.0 69.0 100.0 70.0 75.0 68.0 97.0 70.0 68.0 103.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 16:31:30 INFO - PROCESS | 2318 | Values: 88.0 87.0 89.0 86.0 88.0 85.0 89.0 86.0 89.0 86.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:94.00 stddev:15.08 (16.0%) stddev-sans-first:9.71 16:31:30 INFO - PROCESS | 2318 | Values: 135.0 123.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#7] imaging-desaturate Cycles:10 Average:88.40 Median:84.50 stddev:12.34 (14.6%) stddev-sans-first:13.03 16:31:30 INFO - PROCESS | 2318 | Values: 85.0 85.0 83.0 90.0 123.0 83.0 85.0 84.0 83.0 83.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:0.95 (1.8%) stddev-sans-first:0.97 16:31:30 INFO - PROCESS | 2318 | Values: 54.0 52.0 53.0 54.0 55.0 53.0 52.0 53.0 53.0 54.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:74.00 stddev:4.67 (6.3%) stddev-sans-first:4.51 16:31:30 INFO - PROCESS | 2318 | Values: 68.0 68.0 83.0 78.0 76.0 70.0 72.0 75.0 73.0 72.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:83.50 stddev:9.64 (11.5%) stddev-sans-first:9.81 16:31:30 INFO - PROCESS | 2318 | Values: 83.0 85.0 84.0 83.0 85.0 82.0 94.0 107.0 102.0 102.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:146.00 stddev:22.68 (15.5%) stddev-sans-first:23.62 16:31:30 INFO - PROCESS | 2318 | Values: 137.0 135.0 140.0 150.0 153.0 142.0 135.0 139.0 211.0 150.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:1.58 (1.0%) stddev-sans-first:1.56 16:31:30 INFO - PROCESS | 2318 | Values: 167.0 167.0 167.0 166.0 166.0 165.0 165.0 165.0 162.0 164.0 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - PROCESS | 2318 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:74.00 stddev:9.27 (12.5%) stddev-sans-first:9.41 16:31:30 INFO - PROCESS | 2318 | Values: 66.0 68.0 67.0 67.0 67.0 65.0 85.0 87.0 85.0 80.0 16:31:30 INFO - PROCESS | 2318 | -------- Summary: end -------- 16:31:30 INFO - PROCESS | 2318 | 16:31:30 INFO - TEST-INFO | 2318: exit 0 16:31:30 INFO - TEST-OK | kraken | took 59448ms 16:31:30 INFO - SUITE-END | took 1177s 16:31:31 INFO - Completed test suite (00:19:37) 16:31:31 INFO - javascript benchmark 16:31:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.8486718097683, 3364.7812892162015, 3383.3064221834543, 3405.965940340599, 3451.5512983312183, 5292.80911794846, 5563.36021357544, 5667.9999999999945, 5755.395683453235, 5769.971150144249, 5288.8413347599535, 5780.0751879699255, 5811.157382179582, 5838.474537291643, 5875.7062146892695, 5147.433782283946, 5196.350456192976, 5212.374515058179, 5247.711375874325, 5281.9207711884355, 4443.200223959686, 4850.0, 5338.893222135555, 5356.571474282048, 5410.972945135269, 5091.414487333967, 5279.947200527984, 5285.603579731516, 5304.151335786256, 5323.760430780605, 4317.676174286909, 5139.357580302462, 5185.325907632021, 5188.688678679291, 5194.792208311663, 5080.8221712240265, 5124.538791508745, 5168.121419358718, 5172.301739265189, 5179.378474583036, 5579.944200557983, 5593.272874526286, 5599.356074051496, 5616.0, 5616.045272303718, 6100.722874713385, 6111.724972376253, 6113.358097399798, 6121.081837724332, 6130.887362651042, 6252.218472690914, 6299.433051025429, 6348.365163483661, 6348.682565871734, 6353.777617783356, 5822.097574875901, 5827.271591051119, 5877.4122587740885, 5885.911711324334, 5896.528277737771, 6223.284322302902, 6233.625982441068, 6285.497160227171, 6312.495000399957, 6315.400036996478, 6143.72682107623, 6198.6280823150755, 6224.719887605069, 6226.719797609118, 6232.158658581034, 6302.590331628429, 6302.779402720883, 6314.968425157845, 6318.905216421757, 6338.0, 6079.604825686316, 6109.443041498436, 6267.65527895961, 6274.058891166362, 6284.183056202664, 6111.318268824492, 6149.016157414792, 6164.907526387108, 6172.382761723792, 6238.65687387189, 4910.779014944336, 4920.434150072788, 4933.45731969483, 4936.925946110811, 4944.579710724557, 4375.212461756851, 4437.245668236407, 4441.067375851043, 4452.532484089189, 4492.213862574036, 5650.463205995424, 5671.064274394678, 5685.687287199161, 5713.22871412354, 5739.913901291484, 6254.8123556293385, 6292.4966002719675, 6296.905546416808, 6299.527535434769, 6300.0, 5748.511376533042, 5756.971215143897, 5762.2221000164445, 5769.884602308014, 5773.480386765212, 5343.519083282523, 5397.163439666858, 5416.539594134542, 5456.781728730885, 5467.316585426822, 6339.0, 6370.0, 6370.90443643355, 6372.044193371032, 6380.0, 6303.275123360872, 6304.275008374096, 6304.621722696653, 6314.084457753692, 6318.778842740482, 6190.566660333913, 6205.162303089025, 6206.06908963659, 6269.12232287471, 6289.7169627365965, 6231.2522497298805, 6232.657203853832, 6240.469560087444, 6251.031090181029, 6251.562390632612, 6275.874482510416, 6289.27673317556, 6292.842678933063, 6303.1175635412, 6315.684215789284, 5934.821955341346, 5956.612820166675, 6094.939050609614, 6121.42248870793, 6172.351903050115, 6061.515078793598, 6142.344497607763, 6175.9073613894925, 6201.906971395342, 6213.161223234986, 6019.036954087325, 6045.365236650088, 6051.515878729779, 6058.979634433519, 6103.206583144163, 5894.675792831264, 6023.397660233942, 6104.0, 6175.104609831459, 6182.103594978793, 6009.429104235091, 6023.819285421444, 6055.182550355822, 6077.149199112039, 6121.112438696275], "value": 5710.1886503463975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.4336116993646, 525.1702310349647, 535.3468768102915, 541.2746919128367, 559.6558116758195, 30591.082267531983, 40446.59553404465, 41383.79308103463, 41486.585134148685, 43456.0, 42782.57217427825, 42796.78601606988, 42847.92880177989, 42888.57111428885, 43015.13969720604, 9711.417314961107, 9883.258755593359, 9898.109170174683, 9901.801963960717, 9902.10881020708, 8752.256058235025, 8954.641814327419, 11808.940955295211, 11984.161108722394, 11984.400779960966, 11936.16446848721, 11996.940015299911, 11997.280163190193, 12010.219335743192, 12122.575709850113, 11981.880181198207, 12021.519139234508, 12023.21849079807, 12049.638510844688, 12052.819207711847, 11848.881511184863, 11932.0, 11934.940325298405, 11941.044716422752, 11952.0, 39615.80192099021, 39889.80055099735, 39893.00267493307, 40043.19913601741, 40113.79943100295, 3131.2171957010746, 3416.982915085409, 3625.29306785177, 3698.9075273118387, 3912.1197730510694, 31009.37981240385, 31630.683693163235, 32457.350852983043, 32593.0, 32609.836950815094, 3165.2245199925937, 3272.721818645418, 3327.3121026736194, 3349.9832500837338, 3386.6105397879314, 464764.0, 470821.0, 472246.0, 473186.99999999657, 473571.0, 56142.719286403306, 56402.71798640981, 56516.717416412655, 56526.43473565294, 56633.433665662815, 62539.0, 62653.68673156605, 62753.058704119474, 62804.37195628077, 62891.37108628855, 16152.0, 16424.0, 16424.589385265226, 16464.753028704577, 16465.423710170086, 11604.6518604442, 11900.226485278428, 12830.422630981628, 12874.935625321814, 12896.871031289755, 12293.815592766116, 12408.75182496363, 15025.248737563079, 15028.474003409829, 16351.672966540602, 5967.880642387128, 6990.196127445409, 7037.366637002694, 7054.6825392857445, 7055.753048643272, 737.325347307208, 913.3332667153015, 969.743018100204, 1053.262716098734, 1058.714147180257, 28882.566761498594, 29290.414191716045, 29411.47211555676, 29479.26301842428, 29646.738858725523, 977.3451787302524, 987.3680844259679, 1049.2969710294121, 1055.1664185293685, 1138.8519492465925, 14542.636434089232, 15651.608709782118, 15718.921405392899, 15837.762433563506, 15857.524274271791, 188878.1112188888, 190606.0, 190645.0, 190969.0, 901590.0, 57565.0, 58461.0, 58654.0, 62081.37918620846, 62470.06534379227, 1365.0853927868352, 1394.6931675031476, 1419.7018626088636, 1423.188782394067, 1430.513625367401, 30436.543451847796, 30961.690383096782, 31096.844515777277, 31704.682953170173, 31920.680793191772, 50861.74569127131, 51593.0, 51603.74198128985, 51669.7416512915, 51696.0, 510991.0, 512592.0, 512999.0, 513029.0, 513293.0, 26527.0, 26898.731012689623, 26904.78928448264, 39811.80094099511, 40202.0, 47956.04087918152, 48732.0, 48968.26547601718, 49512.75243623759, 49634.255486167014, 36858.63141368552, 38422.80788596039, 39781.0, 40515.0, 40684.0, 34321.0, 34719.305613888086, 35886.820565897004, 39304.0, 40790.0, 38101.809490952364, 39331.6066839328, 39349.409758853064, 40426.59573404228, 41458.58541414668, 33684.66315336815, 34889.65110348864, 35853.64146358503, 36002.8199858999, 36080.63919360773, 21871.781282186974, 25441.618375724007, 25577.744222557536, 25868.35329116787, 26613.334666633487], "value": 19995.314050985613, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.63878482732434, 373.8149615940108, 380.16553664705964, 382.8755654412316, 403.70327809060336, 15905.204739763009, 15912.0, 20566.383008509732, 21203.257885973955, 21293.467663308405, 20297.39107826764, 20551.486212844688, 20920.686189707165, 21580.352589422302, 21751.238706645272, 5834.183214349973, 5860.384659610732, 5955.523558115362, 6034.306054803688, 6174.567780255384, 5963.194968679238, 6060.636361818305, 6065.909011364833, 6067.24159480065, 6080.847978800543, 4163.895902602444, 5048.268001139832, 6977.686004129826, 6986.755463558777, 7081.858362832741, 6607.93392066078, 6679.198496180489, 7044.22513523512, 7045.330693584101, 7046.436285097206, 5830.941690583082, 5840.3575606683235, 5843.415658434165, 5843.8539036524, 5846.093855452412, 23500.177493787636, 25393.49213015748, 27109.05118320869, 27211.863940680367, 27598.44803103947, 2384.5826980278557, 2437.3784684905413, 2601.713811480736, 2723.850188239646, 3151.4012337656, 14497.0, 14654.047486913423, 15059.322330495153, 15124.168170750605, 15232.695346093127, 1879.5864909719976, 1880.8965506897116, 1934.941951741436, 1944.5916357564786, 1981.3857704111626, 112525.0, 115453.0, 117963.41018294939, 121851.28528150235, 122933.99999999911, 9037.548122593975, 10913.126949843994, 10980.450977451095, 11072.667819965414, 11365.488553015135, 14060.507882224074, 14081.929590351983, 14090.859091408955, 14091.224982626054, 14107.858921410654, 8623.698170564001, 8790.120987901159, 8799.648014079494, 8822.823543529223, 8937.553122343857, 8827.337949653802, 8844.867326990101, 8871.379003469696, 9011.0, 9061.728148155566, 6697.330266973361, 6771.4111520894, 6815.931840681629, 6823.23658918139, 6960.477964152708, 1806.3858288182082, 1851.3890416162635, 1853.7960824309316, 1859.9628007439776, 1862.2085613614431, 908.3505293715026, 912.245020039477, 929.9116583924516, 944.976375590602, 1014.7361685961703, 21562.460938476663, 22946.196883109344, 22969.655455168187, 22996.88501557515, 23003.079876805074, 847.7795773099039, 940.722486866376, 942.2885721280389, 968.3657204531044, 1032.912202462784, 11376.54493820238, 11394.917482839117, 11897.881021189678, 12031.09766767496, 12099.818502722466, 598397.0, 605644.0, 605928.0, 616719.9164004241, 617143.0, 118248.0, 122296.77703223031, 122716.0, 126249.0, 128364.0, 984.9162821160281, 998.7203582996627, 1037.3983089807894, 1037.5071840875523, 1037.6316407675317, 18459.353922612645, 19334.516637084187, 19402.708959365904, 19418.805811942264, 19432.2227110914, 52479.73760131175, 53041.469585305196, 54497.0, 55429.0, 55988.0, 77560.0, 78079.60960195163, 82229.58885205536, 85458.0, 88502.55748721215, 6519.5436319457185, 8032.196780321921, 8175.754727358189, 8432.28325592307, 8473.703420380503, 10550.577976880839, 11717.355545445082, 11940.70148246266, 13022.674433139247, 13565.457381704622, 9430.669926552539, 11232.382218978035, 11722.648320550397, 11732.648020559396, 11734.882651173379, 11100.888991109985, 11245.775084498428, 11477.827832582685, 11664.6500604982, 11698.649040528799, 11369.829452558053, 11557.711057223636, 12009.699757506132, 12021.0, 12030.518779248732, 7963.562004089831, 7991.081025681889, 8325.750227493185, 8436.704715334934, 8446.239838414327, 10329.225308101772, 11301.09591232683, 11655.417229138679, 11743.706407339885, 11785.0], "value": 9999.727072709255, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.3231516556878, 1519.6808670179266, 1554.083090976324, 1766.3729376071494, 1919.47214516008, 7765.417593680475, 8263.173682631734, 8513.318934485242, 8717.476951382921, 8731.476111433318, 8659.567021648923, 8710.73867783966, 8715.0, 8717.171868672465, 8718.694845680402, 2295.8392912496092, 2324.3027091872455, 2325.8604483730946, 2326.0579465316573, 2326.8254880883915, 2300.0, 2330.1844354475966, 2350.9412264693433, 2352.188494969241, 2353.376355265856, 2299.7815207555254, 2318.026428899866, 2320.2691152286975, 2331.1374791327235, 2342.051469154995, 2281.5208806150727, 2306.7312977862193, 2309.353381053291, 2316.8494047887, 2320.3155069254612, 2298.0577963035244, 2305.8616483011074, 2306.6194077977116, 2308.0190918859585, 2311.6301391777233, 7968.282854543119, 8260.256576908107, 8669.869951950726, 8752.956235218782, 8836.204741573225, 5120.103981803207, 5124.846254612367, 5286.709230992294, 5286.97356513215, 5289.867753306198, 3549.982250088759, 3616.2405894762132, 3617.89146325608, 3620.8913732588067, 3631.2192878530977, 1075.7471994081384, 1081.329575663094, 1102.1403305421784, 1110.4558766204582, 1111.8943700348455, 11498.94250528742, 11574.363410012445, 11575.53697852085, 11576.13179011569, 11584.18910676253, 4378.5183629800695, 4409.316555933835, 4547.249703798836, 4623.4451865775645, 4626.699264547794, 7882.802929926797, 9174.954125229331, 9183.173514383738, 9192.81614367709, 9198.126178013077, 5216.843494695166, 5220.164773636276, 5226.033183860922, 5227.163653815372, 5227.18978558324, 4028.576999415078, 4359.520452750195, 4760.357351757469, 4837.467878533358, 4848.927266091011, 3007.65411977621, 3419.281671071504, 3695.1685870678884, 3705.073731567108, 3826.4834247376803, 940.7507010642186, 1135.0919264588297, 1139.7378602921374, 1143.0798207443088, 1154.96535103947, 823.2250760912206, 848.3637272045966, 883.6067949762294, 896.3725392225468, 934.7336009237334, 5455.890882182413, 5496.608975095846, 5500.4224556420995, 5741.081426971665, 5868.149118377897, 886.5168483176684, 917.5594316922501, 934.9672761453453, 945.583943065063, 970.9805803884024, 3615.8734444294323, 3821.2739579480367, 3952.6245006724316, 3969.8412063517135, 3971.6822654187595, 9202.815943681222, 10578.788424231472, 11015.779684406427, 11018.95319944604, 11041.779164416666, 9442.678025076495, 10241.283110182365, 10613.991670791262, 10685.091767199876, 10963.890361096446, 8053.677852885936, 8253.876191857127, 8264.876026859602, 8271.751847444466, 8294.046184688717, 5725.627834190765, 5728.312602487728, 5764.942350576441, 5918.496927761016, 6056.182415373868, 8061.879071814044, 10027.398356098658, 10059.19526437898, 10073.647422340184, 10085.0, 9282.535873206447, 9606.567704453177, 9768.218542516724, 10146.289759717045, 10173.0, 7376.151742549676, 7458.7250888477365, 7562.886556701544, 7563.0, 7679.308862202429, 8093.676252949816, 8362.372822038493, 8446.83106337882, 8580.455518006818, 8580.871286930576, 6434.324395938546, 7312.597807120659, 7329.780106596811, 7376.299251571092, 7559.697612095674, 7269.127704675261, 7278.599677017814, 7292.635368231461, 7309.0, 7321.487495875238, 6898.0, 6900.654967251718, 6918.06606108189, 6980.825479363057, 6989.825254368681, 4634.675572710013, 4645.767711614473, 4684.976575117103, 4692.882677933079, 4808.326834243278, 4782.330473733749, 4925.408950925912, 4932.210846264724, 4958.752062396938, 5083.186690129562], "value": 4483.637288291602, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.59070482733824, 308.3524598343479, 309.01270440941204, 313.7427309606124, 316.0376653091337, 4162.562930892254, 4285.78571071446, 4946.703197808134, 5408.5943554233445, 5417.431169727181, 4620.122176786416, 4844.61243100552, 5230.5031022052935, 5353.5984801139975, 5363.785448582052, 3844.9810800137984, 3875.2830726315583, 4015.6185162409524, 4027.7986100694848, 4142.192272506847, 3347.548081009057, 3995.3207954647633, 4101.835926562934, 4107.260693075245, 4138.985948442636, 3515.806630635314, 4006.4791577095084, 4010.4786377770847, 4012.5586185519714, 4028.756850735157, 3583.122135076897, 3608.8736894208578, 3828.004718773111, 3833.290841194374, 3931.842726290945, 2970.198046527447, 3075.984620076885, 3357.9758173756995, 3494.5631796025496, 3550.218951830619, 4260.998665313648, 4633.49031606523, 4802.399700037496, 5155.458676838915, 5157.690538567698, 4107.301758700998, 4648.233671204921, 4689.226277664181, 4704.741239231841, 4707.71753694776, 5565.749541270652, 5571.4428557144365, 5581.972090139523, 5589.841739417217, 5687.687177205252, 3748.9812550937345, 3879.534455865314, 3887.786171760552, 3889.4943657324648, 3927.2538217738825, 4865.002674451729, 5263.421023687391, 5272.2355258487305, 5942.5840191186635, 6005.369436209223, 3458.5849698036395, 3930.6855451563806, 3937.114149316438, 3939.763614183158, 3959.148783011609, 3414.3683418567643, 4001.7398869073418, 4025.9194816103513, 4035.6973227008093, 4041.2119636671155, 4047.534533528623, 4090.7727681695374, 4185.079282557833, 4554.612857907115, 4679.344891715163, 4606.539346065367, 5418.051840927901, 5585.692786896679, 5661.631993920382, 5708.4862362387585, 1334.472029526187, 1362.2439546051837, 1363.2638375277238, 1363.799856126622, 1364.8157498737544, 796.7370767646663, 826.2439867521271, 830.5043344749112, 840.911704271055, 842.3471809347682, 4361.236783562864, 5124.231365295161, 5239.397469291081, 5252.474752524717, 5311.229871668586, 5020.665390593392, 5064.518870707277, 5752.33848107473, 5758.62568933018, 5777.873314703676, 4588.6099681526775, 4629.86110416688, 4726.385569875894, 4967.726775027409, 5018.272350509155, 2730.645016147888, 2761.8757155928033, 2777.777777777773, 2954.478443601547, 3013.3370658455106, 5103.744812759347, 5684.516816070598, 5973.671448070398, 5988.401159884064, 6006.189164462741, 5213.713245771519, 5840.649561026352, 5897.941020589739, 5910.143029260733, 5973.761049557969, 5985.431384018511, 6024.8853962017165, 6025.276966764016, 6030.7889223876955, 6071.878562428727, 4895.118878601886, 4989.825356112664, 5592.664440133442, 5622.622457497939, 5795.7828855940725, 4700.506446823034, 5064.164412871834, 5712.542996560348, 5767.336756273083, 5775.537956963349, 4777.805548612847, 5797.391273916178, 5950.5239580834295, 6007.3992600739575, 6026.548008899263, 3763.981180094082, 3967.3652215645357, 3980.8805735827973, 3992.540857801274, 4003.4595329631293, 5545.195946587803, 5631.915521267266, 5661.886762264814, 5667.5465962722055, 5677.716114194356, 5013.498650134958, 5521.365043020104, 5529.336479622471, 5600.747966341443, 5616.6068375213345, 5155.43290238081, 5259.368875734936, 5391.811286605107, 5522.3649280331665, 5550.306211723535, 4535.6371490280035, 5223.5559977402345, 5491.533219676213, 5513.421090785409, 5521.199426083115, 5366.946330536644, 5505.697186654773, 5527.309086364205, 5641.971790141023, 5667.3765885753355, 5164.767585458589, 5477.767502311948, 5539.25220095282, 5558.0, 5560.638558493847], "value": 4054.1656470590865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.3089321658863, 684.7911387026954, 692.698676075907, 737.7565403416872, 748.973785917493, 15026.77459838102, 16370.0, 16385.67228655426, 16400.753988690165, 16404.67190656186, 14826.406943722239, 14985.0, 14999.85000149998, 15029.32368043438, 15039.624009399771, 672.6233309346757, 690.1545606631886, 691.9100516932791, 693.1231991530718, 820.6799348254185, 626.9153664255335, 684.0628339175312, 686.4679873098339, 798.4051881348404, 831.4927893984665, 878.1131057631774, 887.6937456577502, 893.9106089391074, 895.136193573205, 896.6368620708591, 888.4580405952429, 888.506878682331, 889.9332550058772, 892.219308105411, 895.1183084661603, 530.9017831701109, 654.5450911616429, 662.0, 662.8574856405847, 663.2041550139852, 14553.0, 14964.0, 14973.850261497355, 14992.475263365839, 15034.323455444423, 13742.244176570284, 14034.92982535091, 14211.644708882257, 14230.501932432418, 14357.353919073666, 12738.74522509554, 12992.415341309665, 12995.220286782824, 13121.0, 13356.465741370333, 4526.977365113186, 4577.267637178069, 4631.282151266559, 4722.917854751955, 4745.738984355859, 116206.41896790462, 117355.0, 117361.41319293348, 117718.0, 117990.99999999914, 10974.231803773659, 11045.779084418267, 11269.436528173559, 11305.0, 11393.145514086476, 22426.663600045686, 22432.775672243068, 22441.663375049386, 22464.32607021792, 22886.313410597708, 7861.174576669482, 7880.605969701492, 7882.132965373804, 7952.2842944134145, 8160.591970401456, 4261.765602891871, 4365.825366985285, 4425.336199570026, 4508.594226519629, 4526.502084770673, 3430.2110514581786, 3539.681428671432, 3548.1129717570607, 3549.982250088733, 3555.4311310190246, 652.9020646902908, 656.143732429175, 661.6327937994363, 672.707372293054, 673.4444083631024, 526.8472143078465, 587.7825204674213, 598.4882925098977, 641.8812519683873, 656.8456412743002, 7665.655045522964, 8427.789305267295, 9182.540872956459, 9383.718488445358, 9968.056229035414, 636.8726254748976, 637.3912913167914, 641.4739913271167, 663.5193303314655, 693.8196069022091, 4314.41755363022, 4321.330193819963, 4498.762840218966, 4501.0, 4512.9774351128035, 132092.0, 133483.0, 136257.0, 139088.0, 139972.0, 112285.43857280661, 124497.0, 125141.37429312796, 125506.37246813708, 127668.36165819112, 102691.0, 107417.46291268493, 110218.0, 111530.88469115367, 113552.0, 6854.794356169323, 7466.701331946661, 8129.065157506962, 8888.333374996771, 9077.90922090797, 48820.0, 57527.0, 59010.40989590049, 59204.0, 60670.393296066475, 78726.0, 81306.18693812986, 87219.25561488607, 87471.0, 88540.5572972131, 11537.769244615229, 11894.643160705193, 11995.64013079609, 12609.243445393306, 12680.809787853004, 13099.476020959437, 13252.370405395546, 13360.866391335961, 13644.181349119084, 13804.240766757523, 11957.76084478323, 12446.190997585129, 12491.812622810483, 12744.980401567667, 13023.869761302265, 10896.727581810519, 12159.046395970032, 12442.253464792142, 13576.864231357955, 13894.861051389356, 13418.93290533541, 13430.597082087554, 13454.394552244976, 13784.724305513633, 13786.724265514433, 10669.35983840972, 10962.561497540328, 11309.604163854226, 11324.433778311217, 11492.540298388305, 10464.372137671764, 11153.944230278797, 11224.60713875043, 11448.484818183035, 11458.0], "value": 7657.036745617578, "unit": "score"}], "name": "dromaeo_css", "value": 7359.831899340168, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0, 112.0, 115.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 123.0, 164.0, 107.0, 105.0, 127.0, 107.0, 139.0, 106.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 185.0, 182.0, 184.0, 186.0, 180.0, 184.0, 181.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 58.0, 88.0, 61.0, 56.0, 64.0, 65.0, 83.0, 56.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 100.0, 70.0, 75.0, 68.0, 97.0, 70.0, 68.0, 103.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 88.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 123.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 90.0, 123.0, 83.0, 85.0, 84.0, 83.0, 83.0], "value": 88.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 55.0, 53.0, 52.0, 53.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 83.0, 78.0, 76.0, 70.0, 72.0, 75.0, 73.0, 72.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 83.0, 85.0, 82.0, 94.0, 107.0, 102.0, 102.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 140.0, 150.0, 153.0, 142.0, 135.0, 139.0, 211.0, 150.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 167.0, 166.0, 166.0, 165.0, 165.0, 165.0, 162.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 67.0, 65.0, 85.0, 87.0, 85.0, 80.0], "value": 73.7, "unit": "score"}], "name": "kraken", "value": 1447.7, "alertThreshold": 2.0}]} 16:31:31 INFO - Return code: 0 16:31:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:31 INFO - # TBPL SUCCESS # 16:31:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:31 INFO - 2016-08-11 16:31:31.365 screenresolution[2322:21543] starting screenresolution argv=screenresolution get 16:31:31 INFO - 2016-08-11 16:31:31.367 screenresolution[2322:21543] Display 0: 1600x1200x32@60 16:31:31 INFO - 2016-08-11 16:31:31.373 screenresolution[2323:21546] starting screenresolution argv=screenresolution list 16:31:31 INFO - Available Modes on Display 0 16:31:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:31 INFO - Intel Iris: 16:31:31 INFO - Chipset Model: Intel Iris 16:31:31 INFO - Type: GPU 16:31:31 INFO - Bus: Built-In 16:31:31 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:31 INFO - Vendor: Intel (0x8086) 16:31:31 INFO - Device ID: 0x0a2e 16:31:31 INFO - Revision ID: 0x0009 16:31:31 INFO - Displays: 16:31:31 INFO - Display: 16:31:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:31 INFO - Display Serial Number: 1600x1200 60 16:31:31 INFO - Main Display: Yes 16:31:31 INFO - Mirror: Off 16:31:31 INFO - Online: Yes 16:31:31 INFO - Rotation: Supported 16:31:31 INFO - Return code: 0 16:31:31 INFO - Running post-action listener: _resource_record_post_action 16:31:31 INFO - [mozharness: 2016-08-11 23:31:31.612700Z] Finished run-tests step (success) 16:31:31 INFO - Running post-run listener: _resource_record_post_run 16:31:31 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 262144; Write bytes: 701583872; Read time: 2; Write time: 2006 16:31:31 INFO - TinderboxPrint: CPU usage
53.4% 16:31:31 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 2 16:31:31 INFO - TinderboxPrint: I/O write bytes / time
701,583,872 / 2,006 16:31:31 INFO - TinderboxPrint: CPU idle
2,261.4 (46.6%) 16:31:31 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 16:31:31 INFO - TinderboxPrint: CPU user
1,619.0 (33.4%) 16:31:31 INFO - TinderboxPrint: Swap in / out
403,451,904 / 0 16:31:31 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401428992; Write bytes: 395277824; Read time: 29784; Write time: 874 16:31:31 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 262144; Write bytes: 292599296; Read time: 2; Write time: 1085 16:31:32 INFO - Running post-run listener: _upload_blobber_files 16:31:32 INFO - Blob upload gear active. 16:31:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:32 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 16:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:31:32 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:31:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:31:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:34 INFO - (blobuploader) - INFO - Done attempting. 16:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:31:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:35 INFO - (blobuploader) - INFO - Done attempting. 16:31:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:31:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:37 INFO - (blobuploader) - INFO - Done attempting. 16:31:37 INFO - (blobuploader) - INFO - Iteration through files over. 16:31:37 INFO - Return code: 0 16:31:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:31:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:31:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edf265aa3851a72891ba85320d9a9b417dd17b53aa502a6da46a26002c38f4bf3a2ba7262acece37de41448446515cb22d8e7fe8809a7934f0d29587ff410ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226df4c85f138217248064f0e09ba48bce469a1ea357c9ffee193e1aa40efbb652fb891fae07f0c09f0e0a18ea1318108ee26176d5cc815c51ce020282052e7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd828f77d4c39e6ac769507000ceb3b46ae8832a5ac5ca34db35f7af03cf9b6d2616410197ac8d5ee300707f81eeb4d0aae78df2d2b59e5ad827578530387af0"} 16:31:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:31:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:31:37 INFO - Contents: 16:31:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edf265aa3851a72891ba85320d9a9b417dd17b53aa502a6da46a26002c38f4bf3a2ba7262acece37de41448446515cb22d8e7fe8809a7934f0d29587ff410ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226df4c85f138217248064f0e09ba48bce469a1ea357c9ffee193e1aa40efbb652fb891fae07f0c09f0e0a18ea1318108ee26176d5cc815c51ce020282052e7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd828f77d4c39e6ac769507000ceb3b46ae8832a5ac5ca34db35f7af03cf9b6d2616410197ac8d5ee300707f81eeb4d0aae78df2d2b59e5ad827578530387af0"} 16:31:37 INFO - Running post-run listener: copy_logs_to_upload_dir 16:31:37 INFO - Copying logs to upload dir... 16:31:37 INFO - mkdir: /builds/slave/test/build/upload/logs 16:31:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.480686 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-11 16:31:37.920654) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 16:31:37.924022) ========= 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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edf265aa3851a72891ba85320d9a9b417dd17b53aa502a6da46a26002c38f4bf3a2ba7262acece37de41448446515cb22d8e7fe8809a7934f0d29587ff410ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226df4c85f138217248064f0e09ba48bce469a1ea357c9ffee193e1aa40efbb652fb891fae07f0c09f0e0a18ea1318108ee26176d5cc815c51ce020282052e7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd828f77d4c39e6ac769507000ceb3b46ae8832a5ac5ca34db35f7af03cf9b6d2616410197ac8d5ee300707f81eeb4d0aae78df2d2b59e5ad827578530387af0"} build_url:https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010997 build_url: 'https://queue.taskcluster.net/v1/task/N-P0vUVFRnuXReKmBpvwwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4edf265aa3851a72891ba85320d9a9b417dd17b53aa502a6da46a26002c38f4bf3a2ba7262acece37de41448446515cb22d8e7fe8809a7934f0d29587ff410ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/226df4c85f138217248064f0e09ba48bce469a1ea357c9ffee193e1aa40efbb652fb891fae07f0c09f0e0a18ea1318108ee26176d5cc815c51ce020282052e7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd828f77d4c39e6ac769507000ceb3b46ae8832a5ac5ca34db35f7af03cf9b6d2616410197ac8d5ee300707f81eeb4d0aae78df2d2b59e5ad827578530387af0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 16:31:37.957361) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:31:37.957834) ========= 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.K7WkVdTJRU/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.pwlIVhwzCC/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005375 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 16:31:38.006733) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 16:31:38.007060) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 16:31:38.007526) ========= ========= Total master_lag: 0.17 =========