builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0085 starttime: 1472380532.31 results: success (0) buildid: 20160827055712 builduid: 65c8787e2cd94a6b8a6faedfa5414b90 revision: 1a5b53a831e5a6c20de1b081c774feb3ff76756c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.307118) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.307627) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.307955) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.587516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.587812) ========= 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.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-28 03:35:32-- 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% 16.6M=0.001s 2016-08-28 03:35:32 (16.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190715 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.816160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.816507) ========= 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.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028920 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:32.866007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-28 03:35:32.866319) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1a5b53a831e5a6c20de1b081c774feb3ff76756c --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 1a5b53a831e5a6c20de1b081c774feb3ff76756c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-28 03:35:32,941 truncating revision to first 12 chars 2016-08-28 03:35:32,941 Setting DEBUG logging. 2016-08-28 03:35:32,941 attempt 1/10 2016-08-28 03:35:32,941 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1a5b53a831e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-28 03:35:33,678 unpacking tar archive at: mozilla-central-1a5b53a831e5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.991345 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-28 03:35:33.880690) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:33.881024) ========= script_repo_revision: 1a5b53a831e5a6c20de1b081c774feb3ff76756c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:33.881511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:33.881788) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-28 03:35:33.900748) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-28 03:35:33.901181) ========= /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.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:35:34 INFO - MultiFileLogger online at 20160828 03:35:34 in /builds/slave/test 03:35:34 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 03:35:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:34 INFO - {'append_to_log': False, 03:35:34 INFO - 'base_work_dir': '/builds/slave/test', 03:35:34 INFO - 'blob_upload_branch': 'Firefox', 03:35:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:34 INFO - 'branch': 'Firefox', 03:35:34 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:34 INFO - 'config_files': ('talos/mac_config.py',), 03:35:34 INFO - 'default_actions': ('clobber', 03:35:34 INFO - 'read-buildbot-config', 03:35:34 INFO - 'download-and-extract', 03:35:34 INFO - 'populate-webroot', 03:35:34 INFO - 'create-virtualenv', 03:35:34 INFO - 'install', 03:35:34 INFO - 'run-tests'), 03:35:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:34 INFO - 'download_minidump_stackwalk': True, 03:35:34 INFO - 'download_symbols': 'ondemand', 03:35:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:34 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:34 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:34 INFO - 'installer_path': 'installer.exe', 03:35:34 INFO - 'log_level': 'info', 03:35:34 INFO - 'log_name': 'talos', 03:35:34 INFO - 'log_to_console': True, 03:35:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:35:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:35:34 INFO - 'opt_config_files': (), 03:35:34 INFO - 'pip_index': False, 03:35:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:35:34 INFO - 'cmd': ('bash', 03:35:34 INFO - '-c', 03:35:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:35:34 INFO - 'enabled': True, 03:35:34 INFO - 'halt_on_failure': False, 03:35:34 INFO - 'name': 'check_screen_resolution'},), 03:35:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:35:34 INFO - 'cmd': ('bash', 03:35:34 INFO - '-c', 03:35:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:35:34 INFO - 'enabled': True, 03:35:34 INFO - 'halt_on_failure': False, 03:35:34 INFO - 'name': 'check_screen_resolution'},), 03:35:34 INFO - 'run_cmd_checks_enabled': True, 03:35:34 INFO - 'sps_profile': False, 03:35:34 INFO - 'sps_profile_interval': 0, 03:35:34 INFO - 'suite': 'dromaeojs', 03:35:34 INFO - 'system_bits': '32', 03:35:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:34 INFO - 'title': 't-yosemite-r7-0085', 03:35:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:34 INFO - 'use_talos_json': True, 03:35:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:34 INFO - 'work_dir': 'build'} 03:35:34 INFO - [mozharness: 2016-08-28 10:35:34.095499Z] Running clobber step. 03:35:34 INFO - Running pre-action listener: _resource_record_pre_action 03:35:34 INFO - Running main action method: clobber 03:35:34 INFO - rmtree: /builds/slave/test/build 03:35:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - [mozharness: 2016-08-28 10:35:36.386804Z] Finished clobber step (success) 03:35:36 INFO - [mozharness: 2016-08-28 10:35:36.386944Z] Running read-buildbot-config step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: read_buildbot_config 03:35:36 INFO - Using buildbot properties: 03:35:36 INFO - { 03:35:36 INFO - "project": "", 03:35:36 INFO - "product": "firefox", 03:35:36 INFO - "script_repo_revision": "production", 03:35:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:35:36 INFO - "repository": "", 03:35:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:35:36 INFO - "buildid": "20160827055712", 03:35:36 INFO - "pgo_build": "False", 03:35:36 INFO - "basedir": "/builds/slave/test", 03:35:36 INFO - "buildnumber": 21, 03:35:36 INFO - "slavename": "t-yosemite-r7-0085", 03:35:36 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:35:36 INFO - "platform": "yosemite_r7", 03:35:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:35:36 INFO - "branch": "mozilla-central", 03:35:36 INFO - "repo_path": "mozilla-central", 03:35:36 INFO - "revision": "1a5b53a831e5a6c20de1b081c774feb3ff76756c", 03:35:36 INFO - "stage_platform": "macosx64", 03:35:36 INFO - "builduid": "65c8787e2cd94a6b8a6faedfa5414b90", 03:35:36 INFO - "slavebuilddir": "test" 03:35:36 INFO - } 03:35:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - [mozharness: 2016-08-28 10:35:36.390253Z] Finished read-buildbot-config step (success) 03:35:36 INFO - [mozharness: 2016-08-28 10:35:36.390379Z] Running download-and-extract step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: download_and_extract 03:35:36 INFO - mkdir: /builds/slave/test/build/tests 03:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:36 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:35:36 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:37 INFO - Downloaded 1199 bytes. 03:35:37 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:35:37 INFO - Using the following test package requirements: 03:35:37 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:35:37 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:35:37 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:35:37 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'jsshell-mac.zip'], 03:35:37 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:35:37 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:35:37 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:35:37 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:35:37 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:35:37 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:35:37 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:35:37 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:37 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:35:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:38 INFO - Downloaded 16369283 bytes. 03:35:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:35:45 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 03:35:45 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')]} 03:35:45 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:35:45 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:46 INFO - Downloaded 16369283 bytes. 03:35:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:35: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')]} 03:35:52 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:35:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:53 INFO - Downloaded 14413998 bytes. 03:35:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:54 INFO - trying https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/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 03:35:58 INFO - Downloaded 111541368 bytes. 03:35:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:35:58 INFO - mkdir: /builds/slave/test/properties 03:35:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:35:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:35:58 INFO - Contents: 03:35:58 INFO - build_url:https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:35:58 INFO - Running post-action listener: _resource_record_post_action 03:35:58 INFO - Running post-action listener: set_extra_try_arguments 03:35:58 INFO - [mozharness: 2016-08-28 10:35:58.628348Z] Finished download-and-extract step (success) 03:35:58 INFO - [mozharness: 2016-08-28 10:35:58.628498Z] Running populate-webroot step. 03:35:58 INFO - Running pre-action listener: _resource_record_pre_action 03:35:58 INFO - Running main action method: populate_webroot 03:35:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:35:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:35:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:35:58 INFO - '--tppagecycles', 03:35:58 INFO - '7'], 03:35:58 INFO - 'tests': ['tsvgm']}}, 03:35:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:35:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:35:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:35:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:35:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['damp', 'tps']}, 03:35:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'tests': ['damp', 'tps']}, 03:35:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['dromaeo_dom']}, 03:35:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:35:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['basic_compositor_video']}, 03:35:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:35:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'other-e10s': {'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['a11yr', 03:35:58 INFO - 'ts_paint', 03:35:58 INFO - 'tpaint', 03:35:58 INFO - 'sessionrestore', 03:35:58 INFO - 'sessionrestore_no_auto_restore', 03:35:58 INFO - 'tabpaint']}, 03:35:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['tsvgx', 03:35:58 INFO - 'tsvgr_opacity', 03:35:58 INFO - 'tart', 03:35:58 INFO - 'tscrollx', 03:35:58 INFO - 'cart']}, 03:35:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:35:58 INFO - 'tsvgr_opacity', 03:35:58 INFO - 'tart', 03:35:58 INFO - 'tscrollx', 03:35:58 INFO - 'cart']}, 03:35:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'talos_options': ['--disable-e10s'], 03:35:58 INFO - 'tests': ['tp5o']}, 03:35:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'tests': ['tp5o']}, 03:35:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'talos_options': ['--disable-e10s', 03:35:58 INFO - '--xperf_path', 03:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:58 INFO - 'tests': ['tp5n']}, 03:35:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:35:58 INFO - 'talos_options': ['--xperf_path', 03:35:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:58 INFO - 'tests': ['tp5n']}}, 03:35:58 INFO - 'talos.zip': {'path': '', 03:35:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:35:58 INFO - Running post-action listener: _resource_record_post_action 03:35:58 INFO - [mozharness: 2016-08-28 10:35:58.647264Z] Finished populate-webroot step (success) 03:35:58 INFO - [mozharness: 2016-08-28 10:35:58.647376Z] Running create-virtualenv step. 03:35:58 INFO - Running pre-action listener: _resource_record_pre_action 03:35:58 INFO - Running main action method: create_virtualenv 03:35:58 INFO - Creating virtualenv /builds/slave/test/build/venv 03:35:58 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 03:35:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:35:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:35:58 INFO - Using real prefix '/tools/python27' 03:35:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:35:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:36:02 INFO - Installing pip.................done. 03:36:02 INFO - Return code: 0 03:36:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:02 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:02 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 03:36:02 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 03:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:02 INFO - 'HOME': '/Users/cltbld', 03:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:02 INFO - 'LOGNAME': 'cltbld', 03:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:02 INFO - 'MOZ_NO_REMOTE': '1', 03:36:02 INFO - 'NO_EM_RESTART': '1', 03:36:02 INFO - 'PAGER': '/bin/cat', 03:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:02 INFO - 'PWD': '/builds/slave/test', 03:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:02 INFO - 'SHELL': '/bin/bash', 03:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:02 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:02 INFO - 'USER': 'cltbld', 03:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:02 INFO - 'XPC_FLAGS': '0x0', 03:36:02 INFO - 'XPC_SERVICE_NAME': '0', 03:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:02 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 03:36:02 INFO - Cleaning up... 03:36:02 INFO - Return code: 0 03:36:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:02 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:02 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 03:36:02 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 03:36:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:02 INFO - 'HOME': '/Users/cltbld', 03:36:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:02 INFO - 'LOGNAME': 'cltbld', 03:36:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:02 INFO - 'MOZ_NO_REMOTE': '1', 03:36:02 INFO - 'NO_EM_RESTART': '1', 03:36:02 INFO - 'PAGER': '/bin/cat', 03:36:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:02 INFO - 'PWD': '/builds/slave/test', 03:36:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:02 INFO - 'SHELL': '/bin/bash', 03:36:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:02 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:02 INFO - 'USER': 'cltbld', 03:36:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:02 INFO - 'XPC_FLAGS': '0x0', 03:36:02 INFO - 'XPC_SERVICE_NAME': '0', 03:36:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:02 INFO - Downloading/unpacking psutil>=3.1.1 03:36: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 03:36: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 03:36: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 03:36: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 03:36: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 03:36: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 03:36:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:36:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:36:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:36:06 INFO - Installing collected packages: psutil 03:36:06 INFO - Running setup.py install for psutil 03:36:06 INFO - building 'psutil._psutil_osx' extension 03:36:06 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 03:36:06 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 03:36:06 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 03:36:07 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 03:36:07 INFO - building 'psutil._psutil_posix' extension 03:36:07 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 03:36:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:36:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:36:07 INFO - ^ 03:36:07 INFO - 1 warning generated. 03:36:07 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 03:36:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:36:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:36:07 INFO - Successfully installed psutil 03:36:07 INFO - Cleaning up... 03:36:07 INFO - Return code: 0 03:36:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:07 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:07 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 03:36:07 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 03:36:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:07 INFO - 'HOME': '/Users/cltbld', 03:36:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:07 INFO - 'LOGNAME': 'cltbld', 03:36:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:07 INFO - 'MOZ_NO_REMOTE': '1', 03:36:07 INFO - 'NO_EM_RESTART': '1', 03:36:07 INFO - 'PAGER': '/bin/cat', 03:36:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:07 INFO - 'PWD': '/builds/slave/test', 03:36:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:07 INFO - 'SHELL': '/bin/bash', 03:36:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:07 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:07 INFO - 'USER': 'cltbld', 03:36:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:07 INFO - 'XPC_FLAGS': '0x0', 03:36:07 INFO - 'XPC_SERVICE_NAME': '0', 03:36:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:36:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:36:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:36:10 INFO - Installing collected packages: mozsystemmonitor 03:36:10 INFO - Running setup.py install for mozsystemmonitor 03:36:10 INFO - Successfully installed mozsystemmonitor 03:36:10 INFO - Cleaning up... 03:36:10 INFO - Return code: 0 03:36:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:10 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:10 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 03:36:10 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 03:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:10 INFO - 'HOME': '/Users/cltbld', 03:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:10 INFO - 'LOGNAME': 'cltbld', 03:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:10 INFO - 'MOZ_NO_REMOTE': '1', 03:36:10 INFO - 'NO_EM_RESTART': '1', 03:36:10 INFO - 'PAGER': '/bin/cat', 03:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:10 INFO - 'PWD': '/builds/slave/test', 03:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:10 INFO - 'SHELL': '/bin/bash', 03:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:10 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:10 INFO - 'USER': 'cltbld', 03:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:10 INFO - 'XPC_FLAGS': '0x0', 03:36:10 INFO - 'XPC_SERVICE_NAME': '0', 03:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:10 INFO - Downloading/unpacking jsonschema==2.5.1 03:36:10 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 03:36:10 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 03:36:10 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 03:36:10 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 03:36:10 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 03:36:10 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 03:36:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:36:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:36:13 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 03:36:13 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 03:36:13 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 03:36:13 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 03:36:13 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 03:36:13 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 03:36:13 INFO - Downloading functools32-3.2.3-2.tar.gz 03:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:36:13 INFO - warning: no files found matching '*.txt' 03:36:13 INFO - no previously-included directories found matching 'build' 03:36:13 INFO - no previously-included directories found matching 'dist' 03:36:13 INFO - no previously-included directories found matching '.git*' 03:36:13 INFO - Installing collected packages: jsonschema, functools32 03:36:13 INFO - Running setup.py install for functools32 03:36:13 INFO - warning: no files found matching '*.txt' 03:36:13 INFO - no previously-included directories found matching 'build' 03:36:13 INFO - no previously-included directories found matching 'dist' 03:36:13 INFO - no previously-included directories found matching '.git*' 03:36:13 INFO - Successfully installed jsonschema functools32 03:36:13 INFO - Cleaning up... 03:36:14 INFO - Return code: 0 03:36:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:36:14 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')]} 03:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:14 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')]} 03:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:14 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:14 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 03:36:14 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 03:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:14 INFO - 'HOME': '/Users/cltbld', 03:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:14 INFO - 'LOGNAME': 'cltbld', 03:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:14 INFO - 'MOZ_NO_REMOTE': '1', 03:36:14 INFO - 'NO_EM_RESTART': '1', 03:36:14 INFO - 'PAGER': '/bin/cat', 03:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:14 INFO - 'PWD': '/builds/slave/test', 03:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:14 INFO - 'SHELL': '/bin/bash', 03:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:14 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:14 INFO - 'USER': 'cltbld', 03:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:14 INFO - 'XPC_FLAGS': '0x0', 03:36:14 INFO - 'XPC_SERVICE_NAME': '0', 03:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:36:14 INFO - Cleaning up... 03:36:14 INFO - Return code: 0 03:36:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:36:14 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')]} 03:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:14 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')]} 03:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:14 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:14 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 03:36:14 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 03:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:14 INFO - 'HOME': '/Users/cltbld', 03:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:14 INFO - 'LOGNAME': 'cltbld', 03:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:14 INFO - 'MOZ_NO_REMOTE': '1', 03:36:14 INFO - 'NO_EM_RESTART': '1', 03:36:14 INFO - 'PAGER': '/bin/cat', 03:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:14 INFO - 'PWD': '/builds/slave/test', 03:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:14 INFO - 'SHELL': '/bin/bash', 03:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:14 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:14 INFO - 'USER': 'cltbld', 03:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:14 INFO - 'XPC_FLAGS': '0x0', 03:36:14 INFO - 'XPC_SERVICE_NAME': '0', 03:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:14 INFO - Downloading/unpacking blobuploader==1.2.4 03:36:14 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 03:36:14 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 03:36:14 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 03:36:14 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 03:36:14 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 03:36:14 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 03:36:17 INFO - Downloading blobuploader-1.2.4.tar.gz 03:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:36:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:36:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:17 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 03:36:18 INFO - Downloading docopt-0.6.1.tar.gz 03:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:36:18 INFO - Installing collected packages: blobuploader, requests, docopt 03:36:18 INFO - Running setup.py install for blobuploader 03:36:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:36:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:36:18 INFO - Running setup.py install for requests 03:36:19 INFO - Running setup.py install for docopt 03:36:19 INFO - Successfully installed blobuploader requests docopt 03:36:19 INFO - Cleaning up... 03:36:19 INFO - Return code: 0 03:36:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:19 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:19 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 03:36:19 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 03:36:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:19 INFO - 'HOME': '/Users/cltbld', 03:36:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:19 INFO - 'LOGNAME': 'cltbld', 03:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:19 INFO - 'MOZ_NO_REMOTE': '1', 03:36:19 INFO - 'NO_EM_RESTART': '1', 03:36:19 INFO - 'PAGER': '/bin/cat', 03:36:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:19 INFO - 'PWD': '/builds/slave/test', 03:36:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:19 INFO - 'SHELL': '/bin/bash', 03:36:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:19 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:19 INFO - 'USER': 'cltbld', 03:36:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:19 INFO - 'XPC_FLAGS': '0x0', 03:36:19 INFO - 'XPC_SERVICE_NAME': '0', 03:36:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:36:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-hvnw6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:36:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-d_oYVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:36:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-6ohPL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:36:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-FZmhCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:36:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:36:19 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0IPSTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Jc9Klq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-I8k3xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-m0FuPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-lG49ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-W6uwHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-kZ3H89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-N_8suI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-aOo5pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:36:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:36:20 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-gGPd2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:21 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-glIaHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:36:21 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-wOs3ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:36:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:36:21 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-CzbZMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:36:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:36:21 INFO - Running setup.py install for manifestparser 03:36:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:36:21 INFO - Running setup.py install for mozcrash 03:36:21 INFO - Running setup.py install for mozdebug 03:36:21 INFO - Running setup.py install for mozdevice 03:36:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:36:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:36:21 INFO - Running setup.py install for mozfile 03:36:22 INFO - Running setup.py install for mozhttpd 03:36:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Running setup.py install for mozinfo 03:36:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Running setup.py install for mozInstall 03:36:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Running setup.py install for mozleak 03:36:22 INFO - Running setup.py install for mozlog 03:36:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Running setup.py install for moznetwork 03:36:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:36:22 INFO - Running setup.py install for mozprocess 03:36:23 INFO - Running setup.py install for mozprofile 03:36:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:36:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:36:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:36:23 INFO - Running setup.py install for mozrunner 03:36:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:36:23 INFO - Running setup.py install for mozscreenshot 03:36:23 INFO - Running setup.py install for moztest 03:36:23 INFO - Running setup.py install for mozversion 03:36:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:36:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:36:23 INFO - Cleaning up... 03:36:23 INFO - Return code: 0 03:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36:23 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')]} 03:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:23 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')]} 03:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:23 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:23 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 03:36:23 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 03:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:23 INFO - 'HOME': '/Users/cltbld', 03:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:23 INFO - 'LOGNAME': 'cltbld', 03:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:23 INFO - 'MOZ_NO_REMOTE': '1', 03:36:23 INFO - 'NO_EM_RESTART': '1', 03:36:23 INFO - 'PAGER': '/bin/cat', 03:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:23 INFO - 'PWD': '/builds/slave/test', 03:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:23 INFO - 'SHELL': '/bin/bash', 03:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:23 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:23 INFO - 'USER': 'cltbld', 03:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:23 INFO - 'XPC_FLAGS': '0x0', 03:36:23 INFO - 'XPC_SERVICE_NAME': '0', 03:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-GvfILg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-nfg3YH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-VjeDXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-R5JbVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-a6A97q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-X6fq3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-KBpaJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:36:24 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)) 03:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:36:24 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-tzzKH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-0mE0nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-FPFS_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-fodWJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mtR7Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-RhYPtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-FhCXqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-CDpQMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-XT8h7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:36:25 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)) 03:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:36:25 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-JlhKVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:36:26 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)) 03:36:26 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)) 03:36:26 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)) 03:36:26 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)) 03:36:26 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)) 03:36:26 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)) 03:36:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:36:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:36:28 INFO - Downloading blessings-1.6.tar.gz 03:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:36:28 INFO - Installing collected packages: blessings 03:36:28 INFO - Running setup.py install for blessings 03:36:28 INFO - Successfully installed blessings 03:36:28 INFO - Cleaning up... 03:36:28 INFO - Return code: 0 03:36:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:36:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:29 INFO - Reading from file tmpfile_stdout 03:36:29 INFO - Current package versions: 03:36:29 INFO - blessings == 1.6 03:36:29 INFO - blobuploader == 1.2.4 03:36:29 INFO - docopt == 0.6.1 03:36:29 INFO - functools32 == 3.2.3-2 03:36:29 INFO - jsonschema == 2.5.1 03:36:29 INFO - manifestparser == 1.1 03:36:29 INFO - mozInstall == 1.12 03:36:29 INFO - mozcrash == 0.17 03:36:29 INFO - mozdebug == 0.1 03:36:29 INFO - mozdevice == 0.48 03:36:29 INFO - mozfile == 1.2 03:36:29 INFO - mozhttpd == 0.7 03:36:29 INFO - mozinfo == 0.9 03:36:29 INFO - mozleak == 0.1 03:36:29 INFO - mozlog == 3.3 03:36:29 INFO - moznetwork == 0.27 03:36:29 INFO - mozprocess == 0.23 03:36:29 INFO - mozprofile == 0.28 03:36:29 INFO - mozrunner == 6.12 03:36:29 INFO - mozscreenshot == 0.1 03:36:29 INFO - mozsystemmonitor == 0.3 03:36:29 INFO - moztest == 0.7 03:36:29 INFO - mozversion == 1.4 03:36:29 INFO - psutil == 3.1.1 03:36:29 INFO - requests == 1.2.3 03:36:29 INFO - wsgiref == 0.1.2 03:36:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36:29 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')]} 03:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:29 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')]} 03:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:29 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:29 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 03:36:29 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 03:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:29 INFO - 'HOME': '/Users/cltbld', 03:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:29 INFO - 'LOGNAME': 'cltbld', 03:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:29 INFO - 'MOZ_NO_REMOTE': '1', 03:36:29 INFO - 'NO_EM_RESTART': '1', 03:36:29 INFO - 'PAGER': '/bin/cat', 03:36:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:29 INFO - 'PWD': '/builds/slave/test', 03:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:29 INFO - 'SHELL': '/bin/bash', 03:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:29 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:29 INFO - 'USER': 'cltbld', 03:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:29 INFO - 'XPC_FLAGS': '0x0', 03:36:29 INFO - 'XPC_SERVICE_NAME': '0', 03:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 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)) 03:36:29 INFO - Cleaning up... 03:36:29 INFO - Return code: 0 03:36:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:36:29 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')]} 03:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:29 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')]} 03:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:36:29 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 0x1055eaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10567f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcea58cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, '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.cGof9cwldb/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.jrFIfjNnBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:36:29 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 03:36:29 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 03:36:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:36:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:29 INFO - 'HOME': '/Users/cltbld', 03:36:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:29 INFO - 'LOGNAME': 'cltbld', 03:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:29 INFO - 'MOZ_NO_REMOTE': '1', 03:36:29 INFO - 'NO_EM_RESTART': '1', 03:36:29 INFO - 'PAGER': '/bin/cat', 03:36:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:29 INFO - 'PWD': '/builds/slave/test', 03:36:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:29 INFO - 'SHELL': '/bin/bash', 03:36:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:36:29 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:36:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:29 INFO - 'USER': 'cltbld', 03:36:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:29 INFO - 'XPC_FLAGS': '0x0', 03:36:29 INFO - 'XPC_SERVICE_NAME': '0', 03:36:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:36:29 INFO - Cleaning up... 03:36:29 INFO - Return code: 0 03:36:29 INFO - Running post-action listener: _resource_record_post_action 03:36:29 INFO - Running post-action listener: _start_resource_monitoring 03:36:29 INFO - Starting resource monitoring. 03:36:29 INFO - [mozharness: 2016-08-28 10:36:29.698661Z] Finished create-virtualenv step (success) 03:36:29 INFO - [mozharness: 2016-08-28 10:36:29.699635Z] Running install step. 03:36:29 INFO - Running pre-action listener: _resource_record_pre_action 03:36:29 INFO - Running main action method: install 03:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:29 INFO - Reading from file tmpfile_stdout 03:36:29 INFO - Detecting whether we're running mozinstall >=1.0... 03:36:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:36:30 INFO - Reading from file tmpfile_stdout 03:36:30 INFO - Output received: 03:36:30 INFO - Usage: mozinstall [options] installer 03:36:30 INFO - Options: 03:36:30 INFO - -h, --help show this help message and exit 03:36:30 INFO - -d DEST, --destination=DEST 03:36:30 INFO - Directory to install application into. [default: 03:36:30 INFO - "/builds/slave/test"] 03:36:30 INFO - --app=APP Application being installed. [default: firefox] 03:36:30 INFO - mkdir: /builds/slave/test/build/application 03:36:30 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'] 03:36:30 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 03:37:03 INFO - Reading from file tmpfile_stdout 03:37:03 INFO - Output received: 03:37:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:37:03 INFO - Running post-action listener: _resource_record_post_action 03:37:03 INFO - [mozharness: 2016-08-28 10:37:03.827001Z] Finished install step (success) 03:37:03 INFO - [mozharness: 2016-08-28 10:37:03.827132Z] Running run-tests step. 03:37:03 INFO - Running pre-action listener: _resource_record_pre_action 03:37:03 INFO - Running main action method: run_tests 03:37:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:37:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:37:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:37:03 INFO - 2016-08-28 03:37:03.841 screenresolution[1684:15197] starting screenresolution argv=screenresolution get 03:37:03 INFO - 2016-08-28 03:37:03.844 screenresolution[1684:15197] Display 0: 1600x1200x32@60 03:37:03 INFO - 2016-08-28 03:37:03.849 screenresolution[1685:15199] starting screenresolution argv=screenresolution list 03:37:03 INFO - Available Modes on Display 0 03:37:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:37:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:37:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:37:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:37:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:37:04 INFO - Intel Iris: 03:37:04 INFO - Chipset Model: Intel Iris 03:37:04 INFO - Type: GPU 03:37:04 INFO - Bus: Built-In 03:37:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:37:04 INFO - Vendor: Intel (0x8086) 03:37:04 INFO - Device ID: 0x0a2e 03:37:04 INFO - Revision ID: 0x0009 03:37:04 INFO - Displays: 03:37:04 INFO - Display: 03:37:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:37:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:37:04 INFO - Display Serial Number: 1600x1200 60 03:37:04 INFO - Main Display: Yes 03:37:04 INFO - Mirror: Off 03:37:04 INFO - Online: Yes 03:37:04 INFO - Rotation: Supported 03:37:04 INFO - Return code: 0 03:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:37:04 INFO - Python 2.7.3 03:37:04 INFO - Return code: 0 03:37:04 INFO - grabbing minidump binary from tooltool 03:37:04 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')]} 03:37:04 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 0x10554ed30>, '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 0x1052b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105546b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:37:04 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 03:37:04 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 03:37:04 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 03:37:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:37:04 INFO - Return code: 0 03:37:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:37:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:37:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:37:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:37:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:37:04 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/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:37:04 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/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cGof9cwldb/Render', 03:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:37:04 INFO - 'HOME': '/Users/cltbld', 03:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:37:04 INFO - 'LOGNAME': 'cltbld', 03:37:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:37:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:37:04 INFO - 'MOZ_NO_REMOTE': '1', 03:37:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:37:04 INFO - 'NO_EM_RESTART': '1', 03:37:04 INFO - 'PAGER': '/bin/cat', 03:37:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:37:04 INFO - 'PWD': '/builds/slave/test', 03:37:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:37:04 INFO - 'SHELL': '/bin/bash', 03:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jrFIfjNnBw/Listeners', 03:37:04 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 03:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:37:04 INFO - 'USER': 'cltbld', 03:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:37:04 INFO - 'XPC_FLAGS': '0x0', 03:37:04 INFO - 'XPC_SERVICE_NAME': '0', 03:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:37:04 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/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:37:04 INFO - mozversion application_buildid: 20160827055712 03:37:04 INFO - mozversion application_changeset: 1a5b53a831e5a6c20de1b081c774feb3ff76756c 03:37:04 INFO - mozversion application_display_name: Nightly 03:37:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:37:04 INFO - mozversion application_name: Firefox 03:37:04 INFO - mozversion application_remotingname: firefox 03:37:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:37:04 INFO - mozversion application_vendor: Mozilla 03:37:04 INFO - mozversion application_version: 51.0a1 03:37:04 INFO - mozversion platform_buildid: 20160827055712 03:37:04 INFO - mozversion platform_changeset: 1a5b53a831e5a6c20de1b081c774feb3ff76756c 03:37:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:37:04 INFO - mozversion platform_version: 51.0a1 03:37:04 INFO - using testdate: 1472380624 03:37:04 INFO - actual date: 1472380624 03:37:04 INFO - starting webserver on 'localhost:49271' 03:37:04 INFO - SUITE-START | Running 2 tests 03:37:04 INFO - TEST-START | dromaeo_css 03:37:04 INFO - operating with platform_type : mac_ 03:37:04 INFO - Initialising browser for dromaeo_css test... 03:37:04 INFO - TEST-INFO | started process 1695 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpUVqyuh/profile http://localhost:49271/getInfo.html) 03:37:06 INFO - PROCESS | 1695 | __metrics Screen width/height:1600/1200 03:37:06 INFO - PROCESS | 1695 | colorDepth:24 03:37:06 INFO - PROCESS | 1695 | Browser inner width/height: 1024/689 03:37:06 INFO - PROCESS | 1695 | __metrics 03:37:06 INFO - PROCESS | 1695 | 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" 03:37:06 INFO - PROCESS | 1695 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:37:07 INFO - TEST-INFO | 1695: exit 0 03:37:07 INFO - Browser initialized. 03:37:07 INFO - Running cycle 1/1 for dromaeo_css test... 03:37:07 INFO - TEST-INFO | started process 1698 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpUVqyuh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:37:19 INFO - PROCESS | 1698 | RSS: Main: 233205760 03:37:19 INFO - PROCESS | 1698 | 03:38:10 INFO - PROCESS | 1698 | 1472380690509 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] 03:40:22 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 03:40:23 INFO - PROCESS | 1698 | RSS: Main: 378134528 03:40:23 INFO - PROCESS | 1698 | 03:43:26 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 03:43:27 INFO - PROCESS | 1698 | RSS: Main: 370135040 03:43:27 INFO - PROCESS | 1698 | 03:46:30 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 03:46:30 INFO - PROCESS | 1698 | RSS: Main: 354791424 03:46:30 INFO - PROCESS | 1698 | 03:49:33 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 03:49:33 INFO - PROCESS | 1698 | RSS: Main: 348975104 03:49:33 INFO - PROCESS | 1698 | 03:52:37 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 03:52:37 INFO - PROCESS | 1698 | RSS: Main: 326852608 03:52:37 INFO - PROCESS | 1698 | 03:55:40 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 03:55:41 INFO - PROCESS | 1698 | RSS: Main: 460296192 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | __start_tp_report 03:55:41 INFO - PROCESS | 1698 | _x_x_mozilla_page_load 03:55:41 INFO - PROCESS | 1698 | _x_x_mozilla_page_load_details 03:55:41 INFO - PROCESS | 1698 | |i|pagename|runs| 03:55:41 INFO - PROCESS | 1698 | |0;dojo.html;3313.4698448248287;3368.7305015598754;3374.7975121492714;3386.9830650846743;3433.124553238924;5199.272101905732;5343.973280133599;5647.689377084259;5721.542276617871;5761.366249712528;5694.601377903548;5719.542436605072;5799.159121927308;5804.883902321962;5837.387074357195;5138.306328645641;5189.714565698885;5258.658187217838;5261.789528418859;5286.365487173884;5293.788248470057;5346.866328341784;5353.277307563488;5365.517103460687;5398.055340315449;5159.974200128994;5186.5332120109;5202.739863006872;5209.609279304067;5281.551068159211;5224.92162617561;5234.005538947587;5239.5546378557865;5248.239005344242;5270.446603106695;5232.424433312332;5246.081935661244;5272.683638981635;5302.257683924254;5310.415854256029;5656.377798442168;5674.347450043257;5794;5807.82576522705;5835.153902684087;6170.136180934673;6221.2845522764555;6228.283747369065;6270.811875643738;6310.432061114522;6282.780102696383;6344.3655634436645;6349.0476428536085;6364.427201551883;6371.936280637181;5585.050541407971;5702.315722113373;5764.279465066867;5784.59507834456;5855.180274761537;6223.222097237845;6227.595206311576;6230.314665386803;6313.116163737081;6333.809985700344;6191.288001879751;6204.255489341218;6204.937950620436;6212.285587157535;6223.222097237845;6145.678679084019;6234.750609975641;6267.529935254783;6334.714937827809;6357.078223657544;5480.342358916955;6137.346341658241;6184.103305020747;6287.1198032275515;6291.276503202099;6261.7182226799905;6288.654124023131;6296.779612713533;6309.495240380758;6318.178636777281;4918.729469879191;5018.89962200754;5020.096382651085;5025.095482813055;5045.697258164522;4383.956160438419;4440.156370289699;4477.843275485342;4519.570640789119;4598.747068911242;5762.711864406763;5790.305163380337;5793.62341447813;5793.826185214451;5795.971020144957;6332.778352757631;6342.460890824239;6343.55595108338;6349.777757778455;6353.3329000455215;5654;5713.143028545668;5733.827985160452;5734.111212761945;5781.566382521328;5425.593080518976;5466.781328746885;5485.670859748428;5502.614816962774;5544.0575102231915;6302.306746257908;6306.810795676137;6313.43179113882;6317.431431171217;6324.588901721373;6299.181106456132;6301.684915754102;6351.428371446592;6358.395952384516;6364.586301890362;6240.781572644935;6257.499400047895;6275.623462592259;6297.779577714941;6318.684065796784;6104.368208815833;6133.610107582747;6149.016157414792;6159.9836027055035;6264.49884009269;6276.811695649138;6310.5898116622275;6314.179156709783;6327.3988971047675;6345.3654634538025;6104.847378815565;6108.541859360477;6117.113018612187;6121.204243448323;6124.356942521149;5076.949230507796;5933.821985340447;6012.188354572252;6088.025915853442;6136.32500424944;5932.199153114447;5932.228810254812;5942.346341902301;5963;5979.162917191509;6071.908921366095;6075.544334175043;6108.969455152695;6127.080937859357;6133.325334213322;5778.653280803165;5838;5854.941450585439;5902.498287645427;5970 03:55:41 INFO - PROCESS | 1698 | |1;ext.html;445.6256744334759;484.81577000739713;530.5622861139558;551.762742020931;557.6180316483207;30654.38691226174;32121.678783212163;32428.67571324286;32431.837840810793;42059.57940420595;41047;43976.56023439765;44014;44246.67259982206;44287;9702.757431064227;9902.603895844157;9929.106380425761;9932;9937.205023598131;8713.564321783917;8993.595288212045;9659.951700241489;9761.316707830454;9773.56018979144;8895.644174233023;8981.281497480186;8997.100289970984;11899.286042837457;12157.817632735516;12042.458089385955;12091.697707557294;12097.334646594434;12110.273383597012;12115.454804533818;11862.932336089707;11935.283882967049;11942.343171125585;11951.103667224907;11958.820617690742;40729.686898530774;40931.1813763726;41339.586604133874;41404.37893431601;41506.584934150575;3242.9837850810595;3254.8372581371073;3277.2188464986507;3307.1236122427576;3390.135515443571;30898;31255.37489250225;31472.37055258882;31657.52513712273;32315;3213.983930080358;3238.8380580970856;3288;3364.9495257571157;3378.577677790276;506590;511640;511671;511947;514343.4282828599;33035.834820825745;57643.13535296974;58135;58254.63090268247;58409.41590584125;33603;33760.662393376246;35675.464868027004;35763.28473430517;36093.27813443769;14612.634684132981;14840.18378989166;14876.181810000337;14886.330115144845;14928.178950157628;12573.748525029449;12740.917022053229;12763.978881689443;12776.488940442277;12870.48518059286;7708.421868359896;7739.071311442663;7770;7776.222377762178;7785.1436342002335;3027.6518200406795;3114.937701245962;3184.0288711942617;3236.401265765841;3281.704646581819;900.5182227508252;909.3589019741066;1028.7376718936623;1048.8479170520231;1080.0873262093519;28531.144065677647;29473.410531789672;29546.40907181844;30031.849840750656;30351;986.590564915552;1035.8394448860438;1091.0671375973586;1092.0717390218251;1109.4064675398783;14617.076584683105;14853.851461485463;15436.305366258546;15819.920900395424;15877.761833572506;720966;794395;804171;812330;1035962;58561;59093;59564.70217648884;59634.70182649059;59683.10475342786;1229.489761748882;1271.2690203133236;1336.3451908564928;1396.1762560089596;1397.0290647999745;27555;29066.564001540417;29913;30325.848370758005;30962.916297929463;48563.27155092747;48682;48950.51049489602;48953.51046489632;49184.50815491942;501752;504540;505772.4711376419;507035;682460;39018.414723778595;40185.99535011648;40889;40954;41162.588374115876;45567.86080348018;45931;46106.538934610224;46511.76744116258;46819.53180468288;37439.06402339855;37650.87047388583;37856.23096831192;38719.6128038716;39428.2114357717;38140.42789358217;38208.61791382162;38628;38861.80569097136;40186.9953251171;36568;36928.630713693594;37666.49334026608;37766.811165944;38096;32058.474243606095;33678.66321336755;33755.15612109717;35202.823985879906;35855.46216806698;24637;24941.376465588506;25314.98740050451;25480.8725956369;25523.744762552138 03:55:41 INFO - PROCESS | 1698 | |2;jquery.html;325.48410768931234;354.2737388353874;377.4131225943658;385.3122176914207;408.44859439756334;15158.242087895602;17182.3127074917;21131.788682113176;21157.471063223427;21234.575308493822;20301.898490507527;21084.472888177803;21117.577648447023;21980.670289945625;22580.43548911278;6727.932720672779;6897.586144831326;6945.340192681687;7014.754483593075;7150.606716630583;4614.446266448031;6940.861182776342;7054.329838665319;7059.399951004145;7063.540869843444;7604.923950760477;7994.5203287802915;8045.919540804605;8068.435209535305;8122.5126492410345;7661.731839385623;7964.880526792073;8030.718924837603;8046.436749427542;8063.838723225503;5756.65460072397;7465.776026719207;7729.497582657109;7819.491733037335;7839.412044096715;29071.41857162845;29075.854620726972;29202.561961570595;29428.558571621445;29439;2825.5055365310986;2877.827330360185;2955.9113226603235;3400.7619466637343;3509;13241;16295.592610184722;16668.83331166685;17172.484825455256;17267.481975540755;1989.3733473955658;2003.1186278037617;2048.713180154765;2063.226290141197;2077.7714451410334;96196;126829.36585317014;130107;131239;136604.9509257362;20930.67426400778;20969.68545471819;21030.633008854682;21569.67645485319;22133.33599992003;34281;34394.31211375808;34423.48364774531;34458.31083378319;34582.82708586491;10139.087482126497;10637.627683031056;10820;11002.284851484628;11250.324980501196;8783.385163038525;8803.471791692391;9017.413868098552;9157.72526824183;9200.125988031126;9575.808483830284;9685.32202745801;9734.561944712505;9823.459709715891;9857.704268871945;3278.5246139309666;3641.9453708194396;3698.9273110798104;3745.9063523412133;3765.6987441004653;821.9506829590124;895.5746020640144;897.5725868438722;969.2585172343163;1015.3349556040805;20194.59610807805;20645.793542064388;20849.89575052115;20866.58266834655;20986.685199722015;925.121134921827;930.0745757970863;935.9812803743887;959.510649568725;1020.2501161646184;10248.795024099476;10939.83590246147;10953.890461095287;10958.232923695425;11007.779844403067;580971;605688;615838;626296;628393.0124891214;114947;119704;122351.38824305822;125269;127466;961.2790407194605;964.1467301438138;1016.8576399304251;1031.9432431216205;1037.180627304421;18459.892406455656;19267.421977340702;19376.4187074388;19429.51426214356;19433;53481.465185347646;53850.19224711554;54419.18371224355;54501;55727.16409253945;77858.6107069461;82255.17744822476;84081.15918840733;84485.57757211174;84841;7004.369606735215;7223.783286501413;7630.618469076635;7913.9604301978125;8156.632951517077;11749.941250293694;12697.238165710087;12707.301098439675;13087.149335293176;13099.672508187372;10013.998600139928;10267.313796888955;10475.52860121281;11101.38942358178;11354.034907032994;10925.07136893373;10941.781164376827;11213.66359009231;11323.32060076398;11515.424228788695;11202.741245048674;11355.886441135814;11577.073834093084;11668.88331116712;11786.882131178578;7156.892646610409;7358.264173582599;7413.888791668021;7514.924850751641;7596.81007974805;9162.67930622425;10951.890481095406;11006.834897476385;11338.489767960627;11544.422778860855 03:55:41 INFO - PROCESS | 1698 | |3;mootools.html;1521.5739592913985;1549.3802479008395;1756.7540544323797;1857.8513718902489;1917.1372882203011;8313.584320783959;8899.777505562364;8899.866502002458;8903.866442003375;8905.64377424903;7742.419318551117;7986.1614530474335;8301.667933282677;8580.871286930686;8686.305095592354;2047.2527527452446;2337.053493335202;2365.0303375616;2365.82256330775;2370.8696021718797;2343.320437073247;2343.7890589846997;2366.520924422236;2387.641853721947;2389.0921449849034;2056.629806634805;2139.155033761663;2268.8638681679067;2361.787439130478;2364.9645255321184;2009.085865930998;2287.49675071484;2354.3054798834387;2384.833061685683;2386.892589833453;2355.5642206191737;2361.6929799126174;2364.893579788896;2366.5858474766846;2445.0342114864707;8642.049374568793;8694.521801300925;8740.257078148365;8753.781155471164;8756.605952732143;5202.08963431398;5342.412334643186;5346.27825243591;5355.223492593591;5358.732063396815;3433.536472576195;3489.0056333944704;3610.249029220826;3645.963540364589;3649.872254471081;1026.8716410448694;1056.234230183111;1066.285588655595;1079.400932482472;1088.1621151713216;11331.37677427741;11475.254108482923;11484.253523520945;11495;11495.827562586568;4510.300903359985;4651.395318608559;4761.904761904742;4765.307601205308;4767.356406885026;9859.063388978035;9863.457509837028;9875.506224688737;9877.753056173653;9883.209343252522;5321.680699158062;5329.227262046981;5330.040592693274;5330.227117068081;5345.866353341197;4234.70357075008;4649.883752906205;4779.808807647655;4840.564349208588;4879.804807807648;3806.3148633246283;3833.271678381099;3845.576986531479;3890.824912878927;3909.3158697227873;1105.7069876482738;1158.6871544682888;1270.5553056430176;1277.2591896699892;1294.7734146524322;735.3969744809206;760.5056713136505;848.245061894914;914.3267819889586;941.0589410589411;5361.463853614685;5718.999175144333;5910.290765108214;5926.496247818897;6025.698715064229;891.8216356728681;903.7921278106072;946.7727745341206;964.8215080210043;1003.8243307421172;3462.79223246601;3869.806509674505;3884.7280690351404;3918.7060970427333;4019.1559772448113;10245.84631230532;10649.9467502662;10654.200934929911;10659.626913058006;10665.466726663635;10589.788204235872;10600.257981941344;10619.044286014141;10625.5218515167;10643.042126208524;7673.731419400294;8065.193480652005;8092.797680057928;8162.591870406456;8164.7550573482895;5805.216295800013;5977.116750359524;5981.970090149522;5984.341722410619;6079.05774604937;9522.571484283086;9626.229901607714;9908.25688073383;9951.203903687543;9954.701358959242;9777.41335519871;10145.492725363554;10158.13655839262;10262.692119236435;10273;7359.77920662381;7426.962865185639;7457.6271186441545;7552.9244707552225;7555.886661700189;8143.79640508992;8227.958860205661;8232.670693172206;8235.464694794819;8247;7280.526765760209;7449.5902725350625;7476.775696729107;7624.733134340271;7633.351165151024;7185.389241914287;7224.349808517259;7286.234945330664;7298.160711518243;7443.25567443273;6773.153355830521;6923.757668481579;6928.653567321513;6934;6963.651817409008;4789.832355867528;4854.80580776765;5010.273510341053;5023.296738456546;5027.974860125676;4764.52372104546;4837.951620483891;4933.309336692938;5065.949340506548;5068.417132029864 03:55:41 INFO - PROCESS | 1698 | |4;prototype.html;297.96605777950504;306.9508878579427;316.5836924444356;320.3001441849559;321.786012301819;4469.486009108953;5180.766865491052;5225.790968361261;5373.570114390849;5439;4963.726995015273;5034.395872495305;5072.337023062893;5385.838424847251;5392.002479541287;4146.564610715876;4153.92303848076;4156.524433825986;4168.791560421982;4178.811953462102;3480.5462149184764;3890.9805450972704;3997.880063598082;4183.769892655902;4229.259879521087;4087.264292427362;4090;4096.959030409703;4105.486814148231;4108.897277568055;4072.8370865165357;4088.9182216355657;4096.91431770581;4101.26177288088;4106.979465102685;3235.7573182011206;3383.543221665093;3605.47720580517;3609.4946707460977;3610.711143108545;5217.460849049539;5239.685618862881;5256.658317209369;5258.947410525922;5260.684358938476;4213.747175169499;4515.2324104902245;4732.692374995614;4738.4550776660435;4747.1960654951245;4928.581070608967;5440.91838622421;5649.576281778842;5652.576056795716;5662.716864156776;3239.9028029159163;3410.1474631342167;3809.53332966807;3848.826802793881;3858.344081506151;5519.586031047687;5657.32112146545;5921.572155416258;5924.496417804492;5942.821715348547;3732.4059778453716;3849.4995650565247;3856.1323702166787;3859.8456061757215;3861.62510056319;3312.4368857293834;3907.8046097695;3911.024932409115;3923.509561304837;3926.450296958428;3708.647678470541;4172.956760809798;4266.274733295348;4562.194250535963;4578.198815207325;5337.426350448056;5339.893202135935;5567.944320556823;5627.818158186745;5637.661740295595;1305.745379650959;1328.8671132886634;1330.374723879775;1330.5409633676366;1331.1680199875077;765.8621448139277;797.2346547314527;814.0922870998794;814.3200427642867;820.8768684697224;4026.3960405938756;4110.540064030561;4442.044960333479;4448.266036104006;4460.193621583264;4340.978295108504;4707.8587642370785;4803.95196048042;4817.422678866789;4836.927446088312;3757.6993840492696;3896.941545876814;4435.600795928382;4435.985433182975;4443.577860103285;2202.3282898715725;2499.750024997522;2679.5846643770246;2729.843423695298;3078;5155.7679904404395;5974.522038237017;6024.246969128859;6043.667598282137;6073.29738266476;5184.9740751296;5441.340391180649;5650.700338922071;5995.702363699947;6086.178365920633;5244.951009797979;5802.361740208658;5962.821115366459;6035.10315048779;6069.453749162597;4893.681910675795;5824.767009319749;5832.475077243069;5852.795152169653;5859.707014649165;5076.578558003765;5423.5932305076485;5734.741936612779;5780.94219057804;5788.855278618068;5620.409856965123;5861.713931566607;6023.909641355295;6041.619825626386;6043.939560604338;3624.3294990426857;3745.213505163946;3768.6231376862092;3770.67949224319;3786.583475817713;5775.971120144372;5777.8555536111935;5785.669296062014;5793.449622285876;5823.563232757476;5323.281357016753;5529.612927095065;5562.749676264659;5609.578157323947;5614.522765564972;5099.209622508517;5486.670799752028;5551.7779288828;5556.860843526986;5558.194061860927;4637.953620463887;4781.9240670848785;5330.974229792809;5460.563154947674;5489.807856724996;5241.816536421207;5503;5554.583406244467;5629.774809007594;5692.406126284647;4938.308636790924;5095.312132862016;5582.469665381782;5586.245856809279;5589.860253493695 03:55:41 INFO - PROCESS | 1698 | |5;yui.html;721.3832173491664;724.1961422820665;774.4230548241561;802.137701970382;809.1463506001169;11840.348780817052;14025.719485610307;15558.52194041567;15584.298706558204;15790.526284211463;14085.366158522866;14169.220692861914;14186.077904936172;14190.574282771508;14536.927315363435;691.2603514239765;720.5496564647095;870.6256309786789;877.8727084572733;898.469902757373;891.7592250092471;904.6924045824418;906.7959709065441;908.4140729229642;911.8176364727082;955.5174636808437;957.3346524165707;957.6169532187146;958.7651025498762;960.1071003966308;739.0724640576096;761.0410882288302;762.0436352377731;956.4691596164126;962.9711108666717;575.7006356694495;700.7442283566512;719.2124623537219;722.0649259209337;722.5520177489938;13801;13961.720765584732;13971.37128829195;13975.510857120054;13983.091099078527;13172.143810652276;13535.932320338336;13590.796138057936;13636.181829090287;13983.650408739863;11732.237404568676;12006.63980080599;12311.4459849307;12684.985201183976;12787.360631968364;4390.297552391634;4463.107378524308;4463.68754187207;4657.161710892038;4659.627229821606;113150;113178.43410782976;114483.42758286238;114618.42690786492;114931;10173.424219640827;10199.592016319413;10510.316829406063;10619.415932123808;10919.781604367869;20044.398668039983;20521.794782051988;20858.582828343348;20886.58226835455;20924;6842.692078856463;7552.998455409321;7949.841003179904;7954.920450795534;8257.504549727035;4308.523361505339;4383.408239887574;4430.62339701129;4533;4581.221192397234;3589.820508974593;3725.776453412804;3735.010222291095;3737.1965027519213;3739.2895349883433;668.2315337361975;673.8349104469338;682.5665702279089;688.4836372720459;698.27728301208;522.9398619158769;523.5393252824653;586.5160649024984;693.2928413018793;709.9041629380071;8935.687250946186;9241.584128714225;9346.299027572823;9481.24150067993;9581.760455988517;588.5556404914263;662.4799532367165;672.6603065452014;677.915260592426;679.1171477079876;3396.7961922284744;4346.391505189276;4522.570355816192;4626.8380606679275;4702.083093796677;118207;160029;162922.18538907467;164169;167214;72215.63892180506;74553.62723186349;84923.05538612237;117388.41305793416;143452.28273858773;67930.66034669794;77126.61436692781;79094.60452697815;106590.9340906581;110319.44840275908;9469.857952130586;9511.239100872051;9798.706038818847;10001.449920254456;10033.44816035125;52887.47112528979;56808;59426.70286648539;61113;64322;56860.715696421255;70869.64565177141;75295.49409011898;84081;86314.7052794196;13208.471661133448;13238.205707657571;13374.665633359244;13450.529231476852;13529.557997314856;15016.3242654083;15257;15533.689326213638;15610.985285956376;15782.974106683028;11607.477663504993;13656.795148072588;13830.446782128602;13924.303784810922;14331.785023224451;13294.854913918225;13305.268210248529;13344.132631378929;13381.53164639233;13835.169889806644;13667.794983075062;13695.520656776966;13839.238841863795;14105.576832695035;14312.476582989884;11184.720381990515;11414.143939204427;11446.771064578495;11474.636763910365;11486.94256528712;11582.630047562234;11633.360165190657;11653.126015549038;11840.585579504675;11860.584879529175 03:55:41 INFO - PROCESS | 1698 | __end_tp_report 03:55:41 INFO - PROCESS | 1698 | __start_cc_report 03:55:41 INFO - PROCESS | 1698 | _x_x_mozilla_cycle_collect,1269 03:55:41 INFO - PROCESS | 1698 | __end_cc_report 03:55:41 INFO - PROCESS | 1698 | __startTimestamp1472381741235__endTimestamp 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | ------- Summary: start ------- 03:55:41 INFO - PROCESS | 1698 | Number of tests: 6 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#0] dojo.html Cycles:165 Average:5766.36 Median:5932.23 stddev:637.02 (10.7%) stddev-sans-first:609.21 03:55:41 INFO - PROCESS | 1698 | Values: 3313.5 3368.7 3374.8 3387.0 3433.1 5199.3 5344.0 5647.7 5721.5 5761.4 5694.6 5719.5 5799.2 5804.9 5837.4 5138.3 5189.7 5258.7 5261.8 5286.4 5293.8 5346.9 5353.3 5365.5 5398.1 5160.0 5186.5 5202.7 5209.6 5281.6 5224.9 5234.0 5239.6 5248.2 5270.4 5232.4 5246.1 5272.7 5302.3 5310.4 5656.4 5674.3 5794.0 5807.8 5835.2 6170.1 6221.3 6228.3 6270.8 6310.4 6282.8 6344.4 6349.0 6364.4 6371.9 5585.1 5702.3 5764.3 5784.6 5855.2 6223.2 6227.6 6230.3 6313.1 6333.8 6191.3 6204.3 6204.9 6212.3 6223.2 6145.7 6234.8 6267.5 6334.7 6357.1 5480.3 6137.3 6184.1 6287.1 6291.3 6261.7 6288.7 6296.8 6309.5 6318.2 4918.7 5018.9 5020.1 5025.1 5045.7 4384.0 4440.2 4477.8 4519.6 4598.7 5762.7 5790.3 5793.6 5793.8 5796.0 6332.8 6342.5 6343.6 6349.8 6353.3 5654.0 5713.1 5733.8 5734.1 5781.6 5425.6 5466.8 5485.7 5502.6 5544.1 6302.3 6306.8 6313.4 6317.4 6324.6 6299.2 6301.7 6351.4 6358.4 6364.6 6240.8 6257.5 6275.6 6297.8 6318.7 6104.4 6133.6 6149.0 6160.0 6264.5 6276.8 6310.6 6314.2 6327.4 6345.4 6104.8 6108.5 6117.1 6121.2 6124.4 5076.9 5933.8 6012.2 6088.0 6136.3 5932.2 5932.2 5942.3 5963.0 5979.2 6071.9 6075.5 6109.0 6127.1 6133.3 5778.7 5838.0 5854.9 5902.5 5970.0 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#1] ext.html Cycles:180 Average:74133.20 Median:36748.32 stddev:174924.54 (476.0%) stddev-sans-first:175327.76 03:55:41 INFO - PROCESS | 1698 | Values: 445.6 484.8 530.6 551.8 557.6 30654.4 32121.7 32428.7 32431.8 42059.6 41047.0 43976.6 44014.0 44246.7 44287.0 9702.8 9902.6 9929.1 9932.0 9937.2 8713.6 8993.6 9660.0 9761.3 9773.6 8895.6 8981.3 8997.1 11899.3 12157.8 12042.5 12091.7 12097.3 12110.3 12115.5 11862.9 11935.3 11942.3 11951.1 11958.8 40729.7 40931.2 41339.6 41404.4 41506.6 3243.0 3254.8 3277.2 3307.1 3390.1 30898.0 31255.4 31472.4 31657.5 32315.0 3214.0 3238.8 3288.0 3364.9 3378.6 506590.0 511640.0 511671.0 511947.0 514343.4 33035.8 57643.1 58135.0 58254.6 58409.4 33603.0 33760.7 35675.5 35763.3 36093.3 14612.6 14840.2 14876.2 14886.3 14928.2 12573.7 12740.9 12764.0 12776.5 12870.5 7708.4 7739.1 7770.0 7776.2 7785.1 3027.7 3114.9 3184.0 3236.4 3281.7 900.5 909.4 1028.7 1048.8 1080.1 28531.1 29473.4 29546.4 30031.8 30351.0 986.6 1035.8 1091.1 1092.1 1109.4 14617.1 14853.9 15436.3 15819.9 15877.8 720966.0 794395.0 804171.0 812330.0 1035962.0 58561.0 59093.0 59564.7 59634.7 59683.1 1229.5 1271.3 1336.3 1396.2 1397.0 27555.0 29066.6 29913.0 30325.8 30962.9 48563.3 48682.0 48950.5 48953.5 49184.5 501752.0 504540.0 505772.5 507035.0 682460.0 39018.4 40186.0 40889.0 40954.0 41162.6 45567.9 45931.0 46106.5 46511.8 46819.5 37439.1 37650.9 37856.2 38719.6 39428.2 38140.4 38208.6 38628.0 38861.8 40187.0 36568.0 36928.6 37666.5 37766.8 38096.0 32058.5 33678.7 33755.2 35202.8 35855.5 24637.0 24941.4 25315.0 25480.9 25523.7 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#2] jquery.html Cycles:180 Average:37143.95 Median:1640.70 stddev:101754.80 (6201.9%) stddev-sans-first:102002.69 03:55:41 INFO - PROCESS | 1698 | Values: 325.5 354.3 377.4 385.3 408.4 15158.2 17182.3 21131.8 21157.5 21234.6 20301.9 21084.5 21117.6 21980.7 22580.4 6727.9 6897.6 6945.3 7014.8 7150.6 4614.4 6940.9 7054.3 7059.4 7063.5 7604.9 7994.5 8045.9 8068.4 8122.5 7661.7 7964.9 8030.7 8046.4 8063.8 5756.7 7465.8 7729.5 7819.5 7839.4 29071.4 29075.9 29202.6 29428.6 29439.0 2825.5 2877.8 2955.9 3400.8 3509.0 13241.0 16295.6 16668.8 17172.5 17267.5 1989.4 2003.1 2048.7 2063.2 2077.8 96196.0 126829.4 130107.0 131239.0 136605.0 20930.7 20969.7 21030.6 21569.7 22133.3 34281.0 34394.3 34423.5 34458.3 34582.8 10139.1 10637.6 10820.0 11002.3 11250.3 8783.4 8803.5 9017.4 9157.7 9200.1 9575.8 9685.3 9734.6 9823.5 9857.7 3278.5 3641.9 3698.9 3745.9 3765.7 822.0 895.6 897.6 969.3 1015.3 20194.6 20645.8 20849.9 20866.6 20986.7 925.1 930.1 936.0 959.5 1020.3 10248.8 10939.8 10953.9 10958.2 11007.8 580971.0 605688.0 615838.0 626296.0 628393.0 114947.0 119704.0 122351.4 125269.0 127466.0 961.3 964.1 1016.9 1031.9 1037.2 18459.9 19267.4 19376.4 19429.5 19433.0 53481.5 53850.2 54419.2 54501.0 55727.2 77858.6 82255.2 84081.2 84485.6 84841.0 7004.4 7223.8 7630.6 7914.0 8156.6 11749.9 12697.2 12707.3 13087.1 13099.7 10014.0 10267.3 10475.5 11101.4 11354.0 10925.1 10941.8 11213.7 11323.3 11515.4 11202.7 11355.9 11577.1 11668.9 11786.9 7156.9 7358.3 7413.9 7514.9 7596.8 9162.7 10951.9 11006.8 11338.5 11544.4 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#3] mootools.html Cycles:180 Average:5662.70 Median:5016.79 stddev:3165.35 (63.1%) stddev-sans-first:3158.93 03:55:41 INFO - PROCESS | 1698 | Values: 1521.6 1549.4 1756.8 1857.9 1917.1 8313.6 8899.8 8899.9 8903.9 8905.6 7742.4 7986.2 8301.7 8580.9 8686.3 2047.3 2337.1 2365.0 2365.8 2370.9 2343.3 2343.8 2366.5 2387.6 2389.1 2056.6 2139.2 2268.9 2361.8 2365.0 2009.1 2287.5 2354.3 2384.8 2386.9 2355.6 2361.7 2364.9 2366.6 2445.0 8642.0 8694.5 8740.3 8753.8 8756.6 5202.1 5342.4 5346.3 5355.2 5358.7 3433.5 3489.0 3610.2 3646.0 3649.9 1026.9 1056.2 1066.3 1079.4 1088.2 11331.4 11475.3 11484.3 11495.0 11495.8 4510.3 4651.4 4761.9 4765.3 4767.4 9859.1 9863.5 9875.5 9877.8 9883.2 5321.7 5329.2 5330.0 5330.2 5345.9 4234.7 4649.9 4779.8 4840.6 4879.8 3806.3 3833.3 3845.6 3890.8 3909.3 1105.7 1158.7 1270.6 1277.3 1294.8 735.4 760.5 848.2 914.3 941.1 5361.5 5719.0 5910.3 5926.5 6025.7 891.8 903.8 946.8 964.8 1003.8 3462.8 3869.8 3884.7 3918.7 4019.2 10245.8 10649.9 10654.2 10659.6 10665.5 10589.8 10600.3 10619.0 10625.5 10643.0 7673.7 8065.2 8092.8 8162.6 8164.8 5805.2 5977.1 5982.0 5984.3 6079.1 9522.6 9626.2 9908.3 9951.2 9954.7 9777.4 10145.5 10158.1 10262.7 10273.0 7359.8 7427.0 7457.6 7552.9 7555.9 8143.8 8228.0 8232.7 8235.5 8247.0 7280.5 7449.6 7476.8 7624.7 7633.4 7185.4 7224.3 7286.2 7298.2 7443.3 6773.2 6923.8 6928.7 6934.0 6963.7 4789.8 4854.8 5010.3 5023.3 5028.0 4764.5 4838.0 4933.3 5065.9 5068.4 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#4] prototype.html Cycles:180 Average:4486.29 Median:4951.02 stddev:1408.65 (28.5%) stddev-sans-first:1377.08 03:55:41 INFO - PROCESS | 1698 | Values: 298.0 307.0 316.6 320.3 321.8 4469.5 5180.8 5225.8 5373.6 5439.0 4963.7 5034.4 5072.3 5385.8 5392.0 4146.6 4153.9 4156.5 4168.8 4178.8 3480.5 3891.0 3997.9 4183.8 4229.3 4087.3 4090.0 4097.0 4105.5 4108.9 4072.8 4088.9 4096.9 4101.3 4107.0 3235.8 3383.5 3605.5 3609.5 3610.7 5217.5 5239.7 5256.7 5258.9 5260.7 4213.7 4515.2 4732.7 4738.5 4747.2 4928.6 5440.9 5649.6 5652.6 5662.7 3239.9 3410.1 3809.5 3848.8 3858.3 5519.6 5657.3 5921.6 5924.5 5942.8 3732.4 3849.5 3856.1 3859.8 3861.6 3312.4 3907.8 3911.0 3923.5 3926.5 3708.6 4173.0 4266.3 4562.2 4578.2 5337.4 5339.9 5567.9 5627.8 5637.7 1305.7 1328.9 1330.4 1330.5 1331.2 765.9 797.2 814.1 814.3 820.9 4026.4 4110.5 4442.0 4448.3 4460.2 4341.0 4707.9 4804.0 4817.4 4836.9 3757.7 3896.9 4435.6 4436.0 4443.6 2202.3 2499.8 2679.6 2729.8 3078.0 5155.8 5974.5 6024.2 6043.7 6073.3 5185.0 5441.3 5650.7 5995.7 6086.2 5245.0 5802.4 5962.8 6035.1 6069.5 4893.7 5824.8 5832.5 5852.8 5859.7 5076.6 5423.6 5734.7 5780.9 5788.9 5620.4 5861.7 6023.9 6041.6 6043.9 3624.3 3745.2 3768.6 3770.7 3786.6 5776.0 5777.9 5785.7 5793.4 5823.6 5323.3 5529.6 5562.7 5609.6 5614.5 5099.2 5486.7 5551.8 5556.9 5558.2 4638.0 4781.9 5331.0 5460.6 5489.8 5241.8 5503.0 5554.6 5629.8 5692.4 4938.3 5095.3 5582.5 5586.2 5589.9 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - PROCESS | 1698 | [#5] yui.html Cycles:180 Average:23113.63 Median:3738.24 stddev:36900.33 (987.1%) stddev-sans-first:36965.54 03:55:41 INFO - PROCESS | 1698 | Values: 721.4 724.2 774.4 802.1 809.1 11840.3 14025.7 15558.5 15584.3 15790.5 14085.4 14169.2 14186.1 14190.6 14536.9 691.3 720.5 870.6 877.9 898.5 891.8 904.7 906.8 908.4 911.8 955.5 957.3 957.6 958.8 960.1 739.1 761.0 762.0 956.5 963.0 575.7 700.7 719.2 722.1 722.6 13801.0 13961.7 13971.4 13975.5 13983.1 13172.1 13535.9 13590.8 13636.2 13983.7 11732.2 12006.6 12311.4 12685.0 12787.4 4390.3 4463.1 4463.7 4657.2 4659.6 113150.0 113178.4 114483.4 114618.4 114931.0 10173.4 10199.6 10510.3 10619.4 10919.8 20044.4 20521.8 20858.6 20886.6 20924.0 6842.7 7553.0 7949.8 7954.9 8257.5 4308.5 4383.4 4430.6 4533.0 4581.2 3589.8 3725.8 3735.0 3737.2 3739.3 668.2 673.8 682.6 688.5 698.3 522.9 523.5 586.5 693.3 709.9 8935.7 9241.6 9346.3 9481.2 9581.8 588.6 662.5 672.7 677.9 679.1 3396.8 4346.4 4522.6 4626.8 4702.1 118207.0 160029.0 162922.2 164169.0 167214.0 72215.6 74553.6 84923.1 117388.4 143452.3 67930.7 77126.6 79094.6 106590.9 110319.4 9469.9 9511.2 9798.7 10001.4 10033.4 52887.5 56808.0 59426.7 61113.0 64322.0 56860.7 70869.6 75295.5 84081.0 86314.7 13208.5 13238.2 13374.7 13450.5 13529.6 15016.3 15257.0 15533.7 15611.0 15783.0 11607.5 13656.8 13830.4 13924.3 14331.8 13294.9 13305.3 13344.1 13381.5 13835.2 13667.8 13695.5 13839.2 14105.6 14312.5 11184.7 11414.1 11446.8 11474.6 11486.9 11582.6 11633.4 11653.1 11840.6 11860.6 03:55:41 INFO - PROCESS | 1698 | -------- Summary: end -------- 03:55:41 INFO - PROCESS | 1698 | 03:55:41 INFO - TEST-INFO | 1698: exit 0 03:55:41 INFO - TEST-OK | dromaeo_css | took 1117404ms 03:55:41 INFO - TEST-START | kraken 03:55:41 INFO - operating with platform_type : mac_ 03:55:41 INFO - Initialising browser for kraken test... 03:55:41 INFO - TEST-INFO | started process 1706 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpx5R8FT/profile http://localhost:49271/getInfo.html) 03:55:44 INFO - PROCESS | 1706 | __metrics Screen width/height:1600/1200 03:55:44 INFO - PROCESS | 1706 | colorDepth:24 03:55:44 INFO - PROCESS | 1706 | Browser inner width/height: 1024/689 03:55:44 INFO - PROCESS | 1706 | __metrics 03:55:44 INFO - PROCESS | 1706 | 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" 03:55:44 INFO - PROCESS | 1706 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:55:44 INFO - TEST-INFO | 1706: exit 0 03:55:44 INFO - Browser initialized. 03:55:44 INFO - Running cycle 1/1 for kraken test... 03:55:44 INFO - TEST-INFO | started process 1707 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpx5R8FT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:55:56 INFO - PROCESS | 1707 | RSS: Main: 233418752 03:55:56 INFO - PROCESS | 1707 | 03:55:59 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 03:56:00 INFO - PROCESS | 1707 | RSS: Main: 291512320 03:56:00 INFO - PROCESS | 1707 | 03:56:03 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 03:56:03 INFO - PROCESS | 1707 | RSS: Main: 286924800 03:56:03 INFO - PROCESS | 1707 | 03:56:07 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 03:56:07 INFO - PROCESS | 1707 | RSS: Main: 379158528 03:56:07 INFO - PROCESS | 1707 | 03:56:09 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 03:56:10 INFO - PROCESS | 1707 | RSS: Main: 281776128 03:56:10 INFO - PROCESS | 1707 | 03:56:12 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:56:13 INFO - PROCESS | 1707 | RSS: Main: 284065792 03:56:13 INFO - PROCESS | 1707 | 03:56:15 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 03:56:16 INFO - PROCESS | 1707 | RSS: Main: 321286144 03:56:16 INFO - PROCESS | 1707 | 03:56:19 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 03:56:19 INFO - PROCESS | 1707 | RSS: Main: 322547712 03:56:19 INFO - PROCESS | 1707 | 03:56:22 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 03:56:23 INFO - PROCESS | 1707 | RSS: Main: 321466368 03:56:23 INFO - PROCESS | 1707 | 03:56:24 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:56:25 INFO - PROCESS | 1707 | RSS: Main: 296624128 03:56:25 INFO - PROCESS | 1707 | 03:56:27 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:56:28 INFO - PROCESS | 1707 | RSS: Main: 302788608 03:56:28 INFO - PROCESS | 1707 | 03:56:30 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:56:30 INFO - PROCESS | 1707 | RSS: Main: 303882240 03:56:30 INFO - PROCESS | 1707 | 03:56:33 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:56:34 INFO - PROCESS | 1707 | RSS: Main: 320540672 03:56:34 INFO - PROCESS | 1707 | 03:56:37 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:56:37 INFO - PROCESS | 1707 | RSS: Main: 316432384 03:56:37 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:56:39 INFO - PROCESS | 1707 | RSS: Main: 359575552 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | __start_tp_report 03:56:39 INFO - PROCESS | 1707 | _x_x_mozilla_page_load 03:56:39 INFO - PROCESS | 1707 | _x_x_mozilla_page_load_details 03:56:39 INFO - PROCESS | 1707 | |i|pagename|runs| 03:56:39 INFO - PROCESS | 1707 | |0;ai-astar;113;113;133;114;115;113;113;113;114;113 03:56:39 INFO - PROCESS | 1707 | |1;audio-beat-detection;145;110;128;159;108;127;126;106;157;107 03:56:39 INFO - PROCESS | 1707 | |2;audio-dft;186;184;185;183;185;180;182;183;184;252 03:56:39 INFO - PROCESS | 1707 | |3;audio-fft;79;72;75;147;75;70;55;68;139;55 03:56:39 INFO - PROCESS | 1707 | |4;audio-oscillator;81;84;123;61;108;125;136;67;71;119 03:56:39 INFO - PROCESS | 1707 | |5;imaging-gaussian-blur;86;83;86;83;86;84;86;83;87;83 03:56:39 INFO - PROCESS | 1707 | |6;imaging-darkroom;93;93;92;93;93;93;93;93;93;91 03:56:39 INFO - PROCESS | 1707 | |7;imaging-desaturate;86;85;86;84;85;87;86;85;86;85 03:56:39 INFO - PROCESS | 1707 | |8;json-parse-financial;52;52;55;53;50;54;52;53;52;52 03:56:39 INFO - PROCESS | 1707 | |9;json-stringify-tinderbox;64;67;68;67;72;77;74;69;70;73 03:56:39 INFO - PROCESS | 1707 | |10;stanford-crypto-aes;86;86;88;82;81;83;84;88;88;89 03:56:39 INFO - PROCESS | 1707 | |11;stanford-crypto-ccm;139;139;136;137;134;145;148;139;179;138 03:56:39 INFO - PROCESS | 1707 | |12;stanford-crypto-pbkdf2;164;166;168;229;167;165;166;169;163;169 03:56:39 INFO - PROCESS | 1707 | |13;stanford-crypto-sha256-iterative;67;66;69;66;66;65;66;68;66;67 03:56:39 INFO - PROCESS | 1707 | __end_tp_report 03:56:39 INFO - PROCESS | 1707 | __start_cc_report 03:56:39 INFO - PROCESS | 1707 | _x_x_mozilla_cycle_collect,1978 03:56:39 INFO - PROCESS | 1707 | __end_cc_report 03:56:39 INFO - PROCESS | 1707 | __startTimestamp1472381799722__endTimestamp 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | ------- Summary: start ------- 03:56:39 INFO - PROCESS | 1707 | Number of tests: 14 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#0] ai-astar Cycles:10 Average:115.40 Median:113.50 stddev:6.22 (5.5%) stddev-sans-first:6.54 03:56:39 INFO - PROCESS | 1707 | Values: 113.0 113.0 133.0 114.0 115.0 113.0 113.0 113.0 114.0 113.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:127.50 stddev:20.39 (16.0%) stddev-sans-first:20.59 03:56:39 INFO - PROCESS | 1707 | Values: 145.0 110.0 128.0 159.0 108.0 127.0 126.0 106.0 157.0 107.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#2] audio-dft Cycles:10 Average:190.40 Median:184.50 stddev:21.71 (11.8%) stddev-sans-first:22.97 03:56:39 INFO - PROCESS | 1707 | Values: 186.0 184.0 185.0 183.0 185.0 180.0 182.0 183.0 184.0 252.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#3] audio-fft Cycles:10 Average:83.50 Median:71.00 stddev:32.41 (45.7%) stddev-sans-first:34.34 03:56:39 INFO - PROCESS | 1707 | Values: 79.0 72.0 75.0 147.0 75.0 70.0 55.0 68.0 139.0 55.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:64.00 stddev:27.65 (43.2%) stddev-sans-first:28.67 03:56:39 INFO - PROCESS | 1707 | Values: 81.0 84.0 123.0 61.0 108.0 125.0 136.0 67.0 71.0 119.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 03:56:39 INFO - PROCESS | 1707 | Values: 86.0 83.0 86.0 83.0 86.0 84.0 86.0 83.0 87.0 83.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 03:56:39 INFO - PROCESS | 1707 | Values: 93.0 93.0 92.0 93.0 93.0 93.0 93.0 93.0 93.0 91.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 03:56:39 INFO - PROCESS | 1707 | Values: 86.0 85.0 86.0 84.0 85.0 87.0 86.0 85.0 86.0 85.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.35 (2.6%) stddev-sans-first:1.42 03:56:39 INFO - PROCESS | 1707 | Values: 52.0 52.0 55.0 53.0 50.0 54.0 52.0 53.0 52.0 52.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.00 stddev:3.90 (5.5%) stddev-sans-first:3.46 03:56:39 INFO - PROCESS | 1707 | Values: 64.0 67.0 68.0 67.0 72.0 77.0 74.0 69.0 70.0 73.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:87.00 stddev:2.84 (3.3%) stddev-sans-first:3.00 03:56:39 INFO - PROCESS | 1707 | Values: 86.0 86.0 88.0 82.0 81.0 83.0 84.0 88.0 88.0 89.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:139.00 stddev:13.18 (9.5%) stddev-sans-first:13.88 03:56:39 INFO - PROCESS | 1707 | Values: 139.0 139.0 136.0 137.0 134.0 145.0 148.0 139.0 179.0 138.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:167.50 stddev:19.92 (11.9%) stddev-sans-first:20.88 03:56:39 INFO - PROCESS | 1707 | Values: 164.0 166.0 168.0 229.0 167.0 165.0 166.0 169.0 163.0 169.0 03:56:39 INFO - PROCESS | 1707 | 03:56:39 INFO - PROCESS | 1707 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.17 (1.8%) stddev-sans-first:1.24 03:56:39 INFO - PROCESS | 1707 | Values: 67.0 66.0 69.0 66.0 66.0 65.0 66.0 68.0 66.0 67.0 03:56:39 INFO - PROCESS | 1707 | -------- Summary: end -------- 03:56:39 INFO - PROCESS | 1707 | 03:56:40 INFO - TEST-INFO | 1707: exit 0 03:56:40 INFO - TEST-OK | kraken | took 58696ms 03:56:40 INFO - SUITE-END | took 1176s 03:56:40 INFO - Completed test suite (00:19:36) 03:56:40 INFO - javascript benchmark 03:56:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.4698448248287, 3368.7305015598754, 3374.7975121492714, 3386.9830650846743, 3433.124553238924, 5199.272101905732, 5343.973280133599, 5647.689377084259, 5721.542276617871, 5761.366249712528, 5694.601377903548, 5719.542436605072, 5799.159121927308, 5804.883902321962, 5837.387074357195, 5138.306328645641, 5189.714565698885, 5258.658187217838, 5261.789528418859, 5286.365487173884, 5293.788248470057, 5346.866328341784, 5353.277307563488, 5365.517103460687, 5398.055340315449, 5159.974200128994, 5186.5332120109, 5202.739863006872, 5209.609279304067, 5281.551068159211, 5224.92162617561, 5234.005538947587, 5239.5546378557865, 5248.239005344242, 5270.446603106695, 5232.424433312332, 5246.081935661244, 5272.683638981635, 5302.257683924254, 5310.415854256029, 5656.377798442168, 5674.347450043257, 5794.0, 5807.82576522705, 5835.153902684087, 6170.136180934673, 6221.2845522764555, 6228.283747369065, 6270.811875643738, 6310.432061114522, 6282.780102696383, 6344.3655634436645, 6349.0476428536085, 6364.427201551883, 6371.936280637181, 5585.050541407971, 5702.315722113373, 5764.279465066867, 5784.59507834456, 5855.180274761537, 6223.222097237845, 6227.595206311576, 6230.314665386803, 6313.116163737081, 6333.809985700344, 6191.288001879751, 6204.255489341218, 6204.937950620436, 6212.285587157535, 6223.222097237845, 6145.678679084019, 6234.750609975641, 6267.529935254783, 6334.714937827809, 6357.078223657544, 5480.342358916955, 6137.346341658241, 6184.103305020747, 6287.1198032275515, 6291.276503202099, 6261.7182226799905, 6288.654124023131, 6296.779612713533, 6309.495240380758, 6318.178636777281, 4918.729469879191, 5018.89962200754, 5020.096382651085, 5025.095482813055, 5045.697258164522, 4383.956160438419, 4440.156370289699, 4477.843275485342, 4519.570640789119, 4598.747068911242, 5762.711864406763, 5790.305163380337, 5793.62341447813, 5793.826185214451, 5795.971020144957, 6332.778352757631, 6342.460890824239, 6343.55595108338, 6349.777757778455, 6353.3329000455215, 5654.0, 5713.143028545668, 5733.827985160452, 5734.111212761945, 5781.566382521328, 5425.593080518976, 5466.781328746885, 5485.670859748428, 5502.614816962774, 5544.0575102231915, 6302.306746257908, 6306.810795676137, 6313.43179113882, 6317.431431171217, 6324.588901721373, 6299.181106456132, 6301.684915754102, 6351.428371446592, 6358.395952384516, 6364.586301890362, 6240.781572644935, 6257.499400047895, 6275.623462592259, 6297.779577714941, 6318.684065796784, 6104.368208815833, 6133.610107582747, 6149.016157414792, 6159.9836027055035, 6264.49884009269, 6276.811695649138, 6310.5898116622275, 6314.179156709783, 6327.3988971047675, 6345.3654634538025, 6104.847378815565, 6108.541859360477, 6117.113018612187, 6121.204243448323, 6124.356942521149, 5076.949230507796, 5933.821985340447, 6012.188354572252, 6088.025915853442, 6136.32500424944, 5932.199153114447, 5932.228810254812, 5942.346341902301, 5963.0, 5979.162917191509, 6071.908921366095, 6075.544334175043, 6108.969455152695, 6127.080937859357, 6133.325334213322, 5778.653280803165, 5838.0, 5854.941450585439, 5902.498287645427, 5970.0], "value": 5725.732760908351, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.6256744334759, 484.81577000739713, 530.5622861139558, 551.762742020931, 557.6180316483207, 30654.38691226174, 32121.678783212163, 32428.67571324286, 32431.837840810793, 42059.57940420595, 41047.0, 43976.56023439765, 44014.0, 44246.67259982206, 44287.0, 9702.757431064227, 9902.603895844157, 9929.106380425761, 9932.0, 9937.205023598131, 8713.564321783917, 8993.595288212045, 9659.951700241489, 9761.316707830454, 9773.56018979144, 8895.644174233023, 8981.281497480186, 8997.100289970984, 11899.286042837457, 12157.817632735516, 12042.458089385955, 12091.697707557294, 12097.334646594434, 12110.273383597012, 12115.454804533818, 11862.932336089707, 11935.283882967049, 11942.343171125585, 11951.103667224907, 11958.820617690742, 40729.686898530774, 40931.1813763726, 41339.586604133874, 41404.37893431601, 41506.584934150575, 3242.9837850810595, 3254.8372581371073, 3277.2188464986507, 3307.1236122427576, 3390.135515443571, 30898.0, 31255.37489250225, 31472.37055258882, 31657.52513712273, 32315.0, 3213.983930080358, 3238.8380580970856, 3288.0, 3364.9495257571157, 3378.577677790276, 506590.0, 511640.0, 511671.0, 511947.0, 514343.4282828599, 33035.834820825745, 57643.13535296974, 58135.0, 58254.63090268247, 58409.41590584125, 33603.0, 33760.662393376246, 35675.464868027004, 35763.28473430517, 36093.27813443769, 14612.634684132981, 14840.18378989166, 14876.181810000337, 14886.330115144845, 14928.178950157628, 12573.748525029449, 12740.917022053229, 12763.978881689443, 12776.488940442277, 12870.48518059286, 7708.421868359896, 7739.071311442663, 7770.0, 7776.222377762178, 7785.1436342002335, 3027.6518200406795, 3114.937701245962, 3184.0288711942617, 3236.401265765841, 3281.704646581819, 900.5182227508252, 909.3589019741066, 1028.7376718936623, 1048.8479170520231, 1080.0873262093519, 28531.144065677647, 29473.410531789672, 29546.40907181844, 30031.849840750656, 30351.0, 986.590564915552, 1035.8394448860438, 1091.0671375973586, 1092.0717390218251, 1109.4064675398783, 14617.076584683105, 14853.851461485463, 15436.305366258546, 15819.920900395424, 15877.761833572506, 720966.0, 794395.0, 804171.0, 812330.0, 1035962.0, 58561.0, 59093.0, 59564.70217648884, 59634.70182649059, 59683.10475342786, 1229.489761748882, 1271.2690203133236, 1336.3451908564928, 1396.1762560089596, 1397.0290647999745, 27555.0, 29066.564001540417, 29913.0, 30325.848370758005, 30962.916297929463, 48563.27155092747, 48682.0, 48950.51049489602, 48953.51046489632, 49184.50815491942, 501752.0, 504540.0, 505772.4711376419, 507035.0, 682460.0, 39018.414723778595, 40185.99535011648, 40889.0, 40954.0, 41162.588374115876, 45567.86080348018, 45931.0, 46106.538934610224, 46511.76744116258, 46819.53180468288, 37439.06402339855, 37650.87047388583, 37856.23096831192, 38719.6128038716, 39428.2114357717, 38140.42789358217, 38208.61791382162, 38628.0, 38861.80569097136, 40186.9953251171, 36568.0, 36928.630713693594, 37666.49334026608, 37766.811165944, 38096.0, 32058.474243606095, 33678.66321336755, 33755.15612109717, 35202.823985879906, 35855.46216806698, 24637.0, 24941.376465588506, 25314.98740050451, 25480.8725956369, 25523.744762552138], "value": 19138.6809886958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.48410768931234, 354.2737388353874, 377.4131225943658, 385.3122176914207, 408.44859439756334, 15158.242087895602, 17182.3127074917, 21131.788682113176, 21157.471063223427, 21234.575308493822, 20301.898490507527, 21084.472888177803, 21117.577648447023, 21980.670289945625, 22580.43548911278, 6727.932720672779, 6897.586144831326, 6945.340192681687, 7014.754483593075, 7150.606716630583, 4614.446266448031, 6940.861182776342, 7054.329838665319, 7059.399951004145, 7063.540869843444, 7604.923950760477, 7994.5203287802915, 8045.919540804605, 8068.435209535305, 8122.5126492410345, 7661.731839385623, 7964.880526792073, 8030.718924837603, 8046.436749427542, 8063.838723225503, 5756.65460072397, 7465.776026719207, 7729.497582657109, 7819.491733037335, 7839.412044096715, 29071.41857162845, 29075.854620726972, 29202.561961570595, 29428.558571621445, 29439.0, 2825.5055365310986, 2877.827330360185, 2955.9113226603235, 3400.7619466637343, 3509.0, 13241.0, 16295.592610184722, 16668.83331166685, 17172.484825455256, 17267.481975540755, 1989.3733473955658, 2003.1186278037617, 2048.713180154765, 2063.226290141197, 2077.7714451410334, 96196.0, 126829.36585317014, 130107.0, 131239.0, 136604.9509257362, 20930.67426400778, 20969.68545471819, 21030.633008854682, 21569.67645485319, 22133.33599992003, 34281.0, 34394.31211375808, 34423.48364774531, 34458.31083378319, 34582.82708586491, 10139.087482126497, 10637.627683031056, 10820.0, 11002.284851484628, 11250.324980501196, 8783.385163038525, 8803.471791692391, 9017.413868098552, 9157.72526824183, 9200.125988031126, 9575.808483830284, 9685.32202745801, 9734.561944712505, 9823.459709715891, 9857.704268871945, 3278.5246139309666, 3641.9453708194396, 3698.9273110798104, 3745.9063523412133, 3765.6987441004653, 821.9506829590124, 895.5746020640144, 897.5725868438722, 969.2585172343163, 1015.3349556040805, 20194.59610807805, 20645.793542064388, 20849.89575052115, 20866.58266834655, 20986.685199722015, 925.121134921827, 930.0745757970863, 935.9812803743887, 959.510649568725, 1020.2501161646184, 10248.795024099476, 10939.83590246147, 10953.890461095287, 10958.232923695425, 11007.779844403067, 580971.0, 605688.0, 615838.0, 626296.0, 628393.0124891214, 114947.0, 119704.0, 122351.38824305822, 125269.0, 127466.0, 961.2790407194605, 964.1467301438138, 1016.8576399304251, 1031.9432431216205, 1037.180627304421, 18459.892406455656, 19267.421977340702, 19376.4187074388, 19429.51426214356, 19433.0, 53481.465185347646, 53850.19224711554, 54419.18371224355, 54501.0, 55727.16409253945, 77858.6107069461, 82255.17744822476, 84081.15918840733, 84485.57757211174, 84841.0, 7004.369606735215, 7223.783286501413, 7630.618469076635, 7913.9604301978125, 8156.632951517077, 11749.941250293694, 12697.238165710087, 12707.301098439675, 13087.149335293176, 13099.672508187372, 10013.998600139928, 10267.313796888955, 10475.52860121281, 11101.38942358178, 11354.034907032994, 10925.07136893373, 10941.781164376827, 11213.66359009231, 11323.32060076398, 11515.424228788695, 11202.741245048674, 11355.886441135814, 11577.073834093084, 11668.88331116712, 11786.882131178578, 7156.892646610409, 7358.264173582599, 7413.888791668021, 7514.924850751641, 7596.81007974805, 9162.67930622425, 10951.890481095406, 11006.834897476385, 11338.489767960627, 11544.422778860855], "value": 11080.980599410852, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.5739592913985, 1549.3802479008395, 1756.7540544323797, 1857.8513718902489, 1917.1372882203011, 8313.584320783959, 8899.777505562364, 8899.866502002458, 8903.866442003375, 8905.64377424903, 7742.419318551117, 7986.1614530474335, 8301.667933282677, 8580.871286930686, 8686.305095592354, 2047.2527527452446, 2337.053493335202, 2365.0303375616, 2365.82256330775, 2370.8696021718797, 2343.320437073247, 2343.7890589846997, 2366.520924422236, 2387.641853721947, 2389.0921449849034, 2056.629806634805, 2139.155033761663, 2268.8638681679067, 2361.787439130478, 2364.9645255321184, 2009.085865930998, 2287.49675071484, 2354.3054798834387, 2384.833061685683, 2386.892589833453, 2355.5642206191737, 2361.6929799126174, 2364.893579788896, 2366.5858474766846, 2445.0342114864707, 8642.049374568793, 8694.521801300925, 8740.257078148365, 8753.781155471164, 8756.605952732143, 5202.08963431398, 5342.412334643186, 5346.27825243591, 5355.223492593591, 5358.732063396815, 3433.536472576195, 3489.0056333944704, 3610.249029220826, 3645.963540364589, 3649.872254471081, 1026.8716410448694, 1056.234230183111, 1066.285588655595, 1079.400932482472, 1088.1621151713216, 11331.37677427741, 11475.254108482923, 11484.253523520945, 11495.0, 11495.827562586568, 4510.300903359985, 4651.395318608559, 4761.904761904742, 4765.307601205308, 4767.356406885026, 9859.063388978035, 9863.457509837028, 9875.506224688737, 9877.753056173653, 9883.209343252522, 5321.680699158062, 5329.227262046981, 5330.040592693274, 5330.227117068081, 5345.866353341197, 4234.70357075008, 4649.883752906205, 4779.808807647655, 4840.564349208588, 4879.804807807648, 3806.3148633246283, 3833.271678381099, 3845.576986531479, 3890.824912878927, 3909.3158697227873, 1105.7069876482738, 1158.6871544682888, 1270.5553056430176, 1277.2591896699892, 1294.7734146524322, 735.3969744809206, 760.5056713136505, 848.245061894914, 914.3267819889586, 941.0589410589411, 5361.463853614685, 5718.999175144333, 5910.290765108214, 5926.496247818897, 6025.698715064229, 891.8216356728681, 903.7921278106072, 946.7727745341206, 964.8215080210043, 1003.8243307421172, 3462.79223246601, 3869.806509674505, 3884.7280690351404, 3918.7060970427333, 4019.1559772448113, 10245.84631230532, 10649.9467502662, 10654.200934929911, 10659.626913058006, 10665.466726663635, 10589.788204235872, 10600.257981941344, 10619.044286014141, 10625.5218515167, 10643.042126208524, 7673.731419400294, 8065.193480652005, 8092.797680057928, 8162.591870406456, 8164.7550573482895, 5805.216295800013, 5977.116750359524, 5981.970090149522, 5984.341722410619, 6079.05774604937, 9522.571484283086, 9626.229901607714, 9908.25688073383, 9951.203903687543, 9954.701358959242, 9777.41335519871, 10145.492725363554, 10158.13655839262, 10262.692119236435, 10273.0, 7359.77920662381, 7426.962865185639, 7457.6271186441545, 7552.9244707552225, 7555.886661700189, 8143.79640508992, 8227.958860205661, 8232.670693172206, 8235.464694794819, 8247.0, 7280.526765760209, 7449.5902725350625, 7476.775696729107, 7624.733134340271, 7633.351165151024, 7185.389241914287, 7224.349808517259, 7286.234945330664, 7298.160711518243, 7443.25567443273, 6773.153355830521, 6923.757668481579, 6928.653567321513, 6934.0, 6963.651817409008, 4789.832355867528, 4854.80580776765, 5010.273510341053, 5023.296738456546, 5027.974860125676, 4764.52372104546, 4837.951620483891, 4933.309336692938, 5065.949340506548, 5068.417132029864], "value": 4547.678916216573, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.96605777950504, 306.9508878579427, 316.5836924444356, 320.3001441849559, 321.786012301819, 4469.486009108953, 5180.766865491052, 5225.790968361261, 5373.570114390849, 5439.0, 4963.726995015273, 5034.395872495305, 5072.337023062893, 5385.838424847251, 5392.002479541287, 4146.564610715876, 4153.92303848076, 4156.524433825986, 4168.791560421982, 4178.811953462102, 3480.5462149184764, 3890.9805450972704, 3997.880063598082, 4183.769892655902, 4229.259879521087, 4087.264292427362, 4090.0, 4096.959030409703, 4105.486814148231, 4108.897277568055, 4072.8370865165357, 4088.9182216355657, 4096.91431770581, 4101.26177288088, 4106.979465102685, 3235.7573182011206, 3383.543221665093, 3605.47720580517, 3609.4946707460977, 3610.711143108545, 5217.460849049539, 5239.685618862881, 5256.658317209369, 5258.947410525922, 5260.684358938476, 4213.747175169499, 4515.2324104902245, 4732.692374995614, 4738.4550776660435, 4747.1960654951245, 4928.581070608967, 5440.91838622421, 5649.576281778842, 5652.576056795716, 5662.716864156776, 3239.9028029159163, 3410.1474631342167, 3809.53332966807, 3848.826802793881, 3858.344081506151, 5519.586031047687, 5657.32112146545, 5921.572155416258, 5924.496417804492, 5942.821715348547, 3732.4059778453716, 3849.4995650565247, 3856.1323702166787, 3859.8456061757215, 3861.62510056319, 3312.4368857293834, 3907.8046097695, 3911.024932409115, 3923.509561304837, 3926.450296958428, 3708.647678470541, 4172.956760809798, 4266.274733295348, 4562.194250535963, 4578.198815207325, 5337.426350448056, 5339.893202135935, 5567.944320556823, 5627.818158186745, 5637.661740295595, 1305.745379650959, 1328.8671132886634, 1330.374723879775, 1330.5409633676366, 1331.1680199875077, 765.8621448139277, 797.2346547314527, 814.0922870998794, 814.3200427642867, 820.8768684697224, 4026.3960405938756, 4110.540064030561, 4442.044960333479, 4448.266036104006, 4460.193621583264, 4340.978295108504, 4707.8587642370785, 4803.95196048042, 4817.422678866789, 4836.927446088312, 3757.6993840492696, 3896.941545876814, 4435.600795928382, 4435.985433182975, 4443.577860103285, 2202.3282898715725, 2499.750024997522, 2679.5846643770246, 2729.843423695298, 3078.0, 5155.7679904404395, 5974.522038237017, 6024.246969128859, 6043.667598282137, 6073.29738266476, 5184.9740751296, 5441.340391180649, 5650.700338922071, 5995.702363699947, 6086.178365920633, 5244.951009797979, 5802.361740208658, 5962.821115366459, 6035.10315048779, 6069.453749162597, 4893.681910675795, 5824.767009319749, 5832.475077243069, 5852.795152169653, 5859.707014649165, 5076.578558003765, 5423.5932305076485, 5734.741936612779, 5780.94219057804, 5788.855278618068, 5620.409856965123, 5861.713931566607, 6023.909641355295, 6041.619825626386, 6043.939560604338, 3624.3294990426857, 3745.213505163946, 3768.6231376862092, 3770.67949224319, 3786.583475817713, 5775.971120144372, 5777.8555536111935, 5785.669296062014, 5793.449622285876, 5823.563232757476, 5323.281357016753, 5529.612927095065, 5562.749676264659, 5609.578157323947, 5614.522765564972, 5099.209622508517, 5486.670799752028, 5551.7779288828, 5556.860843526986, 5558.194061860927, 4637.953620463887, 4781.9240670848785, 5330.974229792809, 5460.563154947674, 5489.807856724996, 5241.816536421207, 5503.0, 5554.583406244467, 5629.774809007594, 5692.406126284647, 4938.308636790924, 5095.312132862016, 5582.469665381782, 5586.245856809279, 5589.860253493695], "value": 4034.551381875317, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.3832173491664, 724.1961422820665, 774.4230548241561, 802.137701970382, 809.1463506001169, 11840.348780817052, 14025.719485610307, 15558.52194041567, 15584.298706558204, 15790.526284211463, 14085.366158522866, 14169.220692861914, 14186.077904936172, 14190.574282771508, 14536.927315363435, 691.2603514239765, 720.5496564647095, 870.6256309786789, 877.8727084572733, 898.469902757373, 891.7592250092471, 904.6924045824418, 906.7959709065441, 908.4140729229642, 911.8176364727082, 955.5174636808437, 957.3346524165707, 957.6169532187146, 958.7651025498762, 960.1071003966308, 739.0724640576096, 761.0410882288302, 762.0436352377731, 956.4691596164126, 962.9711108666717, 575.7006356694495, 700.7442283566512, 719.2124623537219, 722.0649259209337, 722.5520177489938, 13801.0, 13961.720765584732, 13971.37128829195, 13975.510857120054, 13983.091099078527, 13172.143810652276, 13535.932320338336, 13590.796138057936, 13636.181829090287, 13983.650408739863, 11732.237404568676, 12006.63980080599, 12311.4459849307, 12684.985201183976, 12787.360631968364, 4390.297552391634, 4463.107378524308, 4463.68754187207, 4657.161710892038, 4659.627229821606, 113150.0, 113178.43410782976, 114483.42758286238, 114618.42690786492, 114931.0, 10173.424219640827, 10199.592016319413, 10510.316829406063, 10619.415932123808, 10919.781604367869, 20044.398668039983, 20521.794782051988, 20858.582828343348, 20886.58226835455, 20924.0, 6842.692078856463, 7552.998455409321, 7949.841003179904, 7954.920450795534, 8257.504549727035, 4308.523361505339, 4383.408239887574, 4430.62339701129, 4533.0, 4581.221192397234, 3589.820508974593, 3725.776453412804, 3735.010222291095, 3737.1965027519213, 3739.2895349883433, 668.2315337361975, 673.8349104469338, 682.5665702279089, 688.4836372720459, 698.27728301208, 522.9398619158769, 523.5393252824653, 586.5160649024984, 693.2928413018793, 709.9041629380071, 8935.687250946186, 9241.584128714225, 9346.299027572823, 9481.24150067993, 9581.760455988517, 588.5556404914263, 662.4799532367165, 672.6603065452014, 677.915260592426, 679.1171477079876, 3396.7961922284744, 4346.391505189276, 4522.570355816192, 4626.8380606679275, 4702.083093796677, 118207.0, 160029.0, 162922.18538907467, 164169.0, 167214.0, 72215.63892180506, 74553.62723186349, 84923.05538612237, 117388.41305793416, 143452.28273858773, 67930.66034669794, 77126.61436692781, 79094.60452697815, 106590.9340906581, 110319.44840275908, 9469.857952130586, 9511.239100872051, 9798.706038818847, 10001.449920254456, 10033.44816035125, 52887.47112528979, 56808.0, 59426.70286648539, 61113.0, 64322.0, 56860.715696421255, 70869.64565177141, 75295.49409011898, 84081.0, 86314.7052794196, 13208.471661133448, 13238.205707657571, 13374.665633359244, 13450.529231476852, 13529.557997314856, 15016.3242654083, 15257.0, 15533.689326213638, 15610.985285956376, 15782.974106683028, 11607.477663504993, 13656.795148072588, 13830.446782128602, 13924.303784810922, 14331.785023224451, 13294.854913918225, 13305.268210248529, 13344.132631378929, 13381.53164639233, 13835.169889806644, 13667.794983075062, 13695.520656776966, 13839.238841863795, 14105.576832695035, 14312.476582989884, 11184.720381990515, 11414.143939204427, 11446.771064578495, 11474.636763910365, 11486.94256528712, 11582.630047562234, 11633.360165190657, 11653.126015549038, 11840.585579504675, 11860.584879529175], "value": 7729.4849830707835, "unit": "score"}], "name": "dromaeo_css", "value": 7459.059939273315, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 133.0, 114.0, 115.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 128.0, 159.0, 108.0, 127.0, 126.0, 106.0, 157.0, 107.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 185.0, 183.0, 185.0, 180.0, 182.0, 183.0, 184.0, 252.0], "value": 190.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 75.0, 147.0, 75.0, 70.0, 55.0, 68.0, 139.0, 55.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 123.0, 61.0, 108.0, 125.0, 136.0, 67.0, 71.0, 119.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 91.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 85.0, 87.0, 86.0, 85.0, 86.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 55.0, 53.0, 50.0, 54.0, 52.0, 53.0, 52.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 68.0, 67.0, 72.0, 77.0, 74.0, 69.0, 70.0, 73.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 82.0, 81.0, 83.0, 84.0, 88.0, 88.0, 89.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 136.0, 137.0, 134.0, 145.0, 148.0, 139.0, 179.0, 138.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 168.0, 229.0, 167.0, 165.0, 166.0, 169.0, 163.0, 169.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 66.0, 66.0, 65.0, 66.0, 68.0, 66.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1467.7, "alertThreshold": 2.0}]} 03:56:40 INFO - Return code: 0 03:56:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:56:41 INFO - # TBPL SUCCESS # 03:56:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:56:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:56:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:56:41 INFO - 2016-08-28 03:56:41.038 screenresolution[1711:17340] starting screenresolution argv=screenresolution get 03:56:41 INFO - 2016-08-28 03:56:41.040 screenresolution[1711:17340] Display 0: 1600x1200x32@60 03:56:41 INFO - 2016-08-28 03:56:41.046 screenresolution[1712:17343] starting screenresolution argv=screenresolution list 03:56:41 INFO - Available Modes on Display 0 03:56:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:56:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:56:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:56:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:56:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:56:41 INFO - Intel Iris: 03:56:41 INFO - Chipset Model: Intel Iris 03:56:41 INFO - Type: GPU 03:56:41 INFO - Bus: Built-In 03:56:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:56:41 INFO - Vendor: Intel (0x8086) 03:56:41 INFO - Device ID: 0x0a2e 03:56:41 INFO - Revision ID: 0x0009 03:56:41 INFO - Displays: 03:56:41 INFO - Display: 03:56:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:56:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:56:41 INFO - Display Serial Number: 1600x1200 60 03:56:41 INFO - Main Display: Yes 03:56:41 INFO - Mirror: Off 03:56:41 INFO - Online: Yes 03:56:41 INFO - Rotation: Supported 03:56:41 INFO - Return code: 0 03:56:41 INFO - Running post-action listener: _resource_record_post_action 03:56:41 INFO - [mozharness: 2016-08-28 10:56:41.281478Z] Finished run-tests step (success) 03:56:41 INFO - Running post-run listener: _resource_record_post_run 03:56:41 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31464448; Write bytes: 707395584; Read time: 303; Write time: 2098 03:56:41 INFO - TinderboxPrint: CPU usage
54.0% 03:56:41 INFO - TinderboxPrint: I/O read bytes / time
31,464,448 / 303 03:56:41 INFO - TinderboxPrint: I/O write bytes / time
707,395,584 / 2,098 03:56:41 INFO - TinderboxPrint: CPU idle
2,227.1 (46.0%) 03:56:41 INFO - TinderboxPrint: CPU system
971.6 (20.1%) 03:56:41 INFO - TinderboxPrint: CPU user
1,647.0 (34.0%) 03:56:41 INFO - TinderboxPrint: Swap in / out
433,213,440 / 0 03:56:41 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 407065088; Write bytes: 408864256; Read time: 29734; Write time: 829 03:56:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29219840; Write bytes: 296970752; Read time: 278; Write time: 1265 03:56:41 INFO - Running post-run listener: _upload_blobber_files 03:56:41 INFO - Blob upload gear active. 03:56:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:56:41 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 03:56:41 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'] 03:56:41 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 03:56:42 INFO - Traceback (most recent call last): 03:56:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 03:56:42 INFO - main() 03:56:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 03:56:42 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 03:56:42 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 03:56:42 INFO - return set(response.json().get('whitelist', [])) 03:56:42 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/models.py", line 651, in json 03:56:42 INFO - return json.loads(self.text or self.content, **kwargs) 03:56:42 INFO - File "/tools/python27/lib/python2.7/json/__init__.py", line 326, in loads 03:56:42 INFO - return _default_decoder.decode(s) 03:56:42 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 366, in decode 03:56:42 INFO - obj, end = self.raw_decode(s, idx=_w(s, 0).end()) 03:56:42 INFO - File "/tools/python27/lib/python2.7/json/decoder.py", line 384, in raw_decode 03:56:42 INFO - raise ValueError("No JSON object could be decoded") 03:56:42 INFO - ValueError: No JSON object could be decoded 03:56:42 ERROR - Return code: 1 03:56:42 INFO - Setting buildbot property blobber_files to {} 03:56:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:56:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:56:42 INFO - Contents: 03:56:42 INFO - blobber_files:{} 03:56:42 INFO - Running post-run listener: copy_logs_to_upload_dir 03:56:42 INFO - Copying logs to upload dir... 03:56:42 INFO - mkdir: /builds/slave/test/build/upload/logs 03:56:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.296970 ========= master_lag: 4.70 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-28 03:56:46.894944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 13 secs) (at 2016-08-28 03:56:46.899321) ========= 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.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/fPR4ZYQcRaG19UpRQWLg1g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{}' ========= master_lag: 13.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 13 secs) (at 2016-08-28 03:57:00.014332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-08-28 03:57:00.014614) ========= 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.cGof9cwldb/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.jrFIfjNnBw/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005409 ========= master_lag: 5.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-08-28 03:57:05.033915) ========= ========= Started reboot skipped (results: 3, elapsed: 7 secs) (at 2016-08-28 03:57:05.034364) ========= ========= Finished reboot skipped (results: 3, elapsed: 7 secs) (at 2016-08-28 03:57:12.661799) ========= ========= Total master_lag: 22.90 =========