builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0105 starttime: 1467131278.31 results: success (0) buildid: 20160628071022 builduid: 17a19734969742aa8e8d11147118e308 revision: e45890951ce77c3df05575bd54072b9f300d77b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.313147) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.313604) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.313879) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.338623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.338950) ========= 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.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-06-28 09:27:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2016-06-28 09:27:58 (200 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.485569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.485966) ========= 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.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.028973 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:27:58.536505) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-06-28 09:27:58.536969) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e45890951ce77c3df05575bd54072b9f300d77b0 --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 e45890951ce77c3df05575bd54072b9f300d77b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-06-28 09:27:58,610 truncating revision to first 12 chars 2016-06-28 09:27:58,610 Setting DEBUG logging. 2016-06-28 09:27:58,610 attempt 1/10 2016-06-28 09:27:58,610 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e45890951ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 09:28:00,339 unpacking tar archive at: mozilla-central-e45890951ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.001050 ========= master_lag: 2.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-06-28 09:28:02.610675) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 09:28:02.611073) ========= script_repo_revision: e45890951ce77c3df05575bd54072b9f300d77b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 09:28:02.611496) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 09:28:02.611759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 09:28:02.631323) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-28 09:28:02.631638) ========= /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.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:28:02 INFO - MultiFileLogger online at 20160628 09:28:02 in /builds/slave/test 09:28:02 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 09:28:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:02 INFO - {'append_to_log': False, 09:28:02 INFO - 'base_work_dir': '/builds/slave/test', 09:28:02 INFO - 'blob_upload_branch': 'Firefox', 09:28:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:02 INFO - 'branch': 'Firefox', 09:28:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:02 INFO - 'config_files': ('talos/mac_config.py',), 09:28:02 INFO - 'default_actions': ('clobber', 09:28:02 INFO - 'read-buildbot-config', 09:28:02 INFO - 'download-and-extract', 09:28:02 INFO - 'populate-webroot', 09:28:02 INFO - 'create-virtualenv', 09:28:02 INFO - 'install', 09:28:02 INFO - 'run-tests'), 09:28:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:02 INFO - 'download_minidump_stackwalk': True, 09:28:02 INFO - 'download_symbols': 'ondemand', 09:28:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:02 INFO - 'installer_path': 'installer.exe', 09:28:02 INFO - 'log_level': 'info', 09:28:02 INFO - 'log_name': 'talos', 09:28:02 INFO - 'log_to_console': True, 09:28:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:28:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:28:02 INFO - 'opt_config_files': (), 09:28:02 INFO - 'pip_index': False, 09:28:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:02 INFO - 'cmd': ('bash', 09:28:02 INFO - '-c', 09:28:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:02 INFO - 'enabled': True, 09:28:02 INFO - 'halt_on_failure': False, 09:28:02 INFO - 'name': 'check_screen_resolution'},), 09:28:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:02 INFO - 'cmd': ('bash', 09:28:02 INFO - '-c', 09:28:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:02 INFO - 'enabled': True, 09:28:02 INFO - 'halt_on_failure': False, 09:28:02 INFO - 'name': 'check_screen_resolution'},), 09:28:02 INFO - 'run_cmd_checks_enabled': True, 09:28:02 INFO - 'sps_profile': False, 09:28:02 INFO - 'sps_profile_interval': 0, 09:28:02 INFO - 'suite': 'dromaeojs', 09:28:02 INFO - 'system_bits': '32', 09:28:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:02 INFO - 'title': 't-yosemite-r7-0105', 09:28:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:02 INFO - 'use_talos_json': True, 09:28:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:02 INFO - 'work_dir': 'build'} 09:28:02 INFO - ##### 09:28:02 INFO - ##### Running clobber step. 09:28:02 INFO - ##### 09:28:02 INFO - Running pre-action listener: _resource_record_pre_action 09:28:02 INFO - Running main action method: clobber 09:28:02 INFO - rmtree: /builds/slave/test/build 09:28:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:04 INFO - Running post-action listener: _resource_record_post_action 09:28:04 INFO - ##### 09:28:04 INFO - ##### Finished clobber step (success) 09:28:04 INFO - ##### 09:28:04 INFO - ##### 09:28:04 INFO - ##### Running read-buildbot-config step. 09:28:04 INFO - ##### 09:28:04 INFO - Running pre-action listener: _resource_record_pre_action 09:28:04 INFO - Running main action method: read_buildbot_config 09:28:04 INFO - Using buildbot properties: 09:28:04 INFO - { 09:28:04 INFO - "project": "", 09:28:04 INFO - "product": "firefox", 09:28:04 INFO - "script_repo_revision": "production", 09:28:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:28:04 INFO - "repository": "", 09:28:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:28:04 INFO - "buildid": "20160628071022", 09:28:04 INFO - "pgo_build": "False", 09:28:04 INFO - "basedir": "/builds/slave/test", 09:28:04 INFO - "buildnumber": 31, 09:28:04 INFO - "slavename": "t-yosemite-r7-0105", 09:28:04 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:28:04 INFO - "platform": "yosemite_r7", 09:28:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:28:04 INFO - "branch": "mozilla-central", 09:28:04 INFO - "repo_path": "mozilla-central", 09:28:04 INFO - "revision": "e45890951ce77c3df05575bd54072b9f300d77b0", 09:28:04 INFO - "stage_platform": "macosx64", 09:28:04 INFO - "builduid": "17a19734969742aa8e8d11147118e308", 09:28:04 INFO - "slavebuilddir": "test" 09:28:04 INFO - } 09:28:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:28:04 INFO - Running post-action listener: _resource_record_post_action 09:28:04 INFO - ##### 09:28:04 INFO - ##### Finished read-buildbot-config step (success) 09:28:04 INFO - ##### 09:28:04 INFO - ##### 09:28:04 INFO - ##### Running download-and-extract step. 09:28:04 INFO - ##### 09:28:04 INFO - Running pre-action listener: _resource_record_pre_action 09:28:04 INFO - Running main action method: download_and_extract 09:28:04 INFO - mkdir: /builds/slave/test/build/tests 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:04 INFO - https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:28:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:28:06 INFO - Downloaded 1199 bytes. 09:28:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:06 INFO - Using the following test package requirements: 09:28:06 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:06 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:28:06 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:28:06 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'jsshell-mac.zip'], 09:28:06 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:28:06 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:06 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:28:06 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:28:06 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:28:06 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:06 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:28:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:28:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:06 INFO - https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:28:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:07 INFO - Downloaded 18786884 bytes. 09:28:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:28:11 INFO - Return code: 0 09:28:11 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:11 INFO - https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:28:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:12 INFO - Downloaded 18786884 bytes. 09:28:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:28:15 INFO - Return code: 0 09:28:15 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:15 INFO - https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:28:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:28:16 INFO - Downloaded 14405245 bytes. 09:28:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:28:17 INFO - Return code: 0 09:28:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:17 INFO - https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:28:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:28:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:28:20 INFO - Downloaded 112365986 bytes. 09:28:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:20 INFO - mkdir: /builds/slave/test/properties 09:28:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:28:20 INFO - Writing to file /builds/slave/test/properties/build_url 09:28:20 INFO - Contents: 09:28:20 INFO - build_url:https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:21 INFO - Running post-action listener: _resource_record_post_action 09:28:21 INFO - Running post-action listener: set_extra_try_arguments 09:28:21 INFO - ##### 09:28:21 INFO - ##### Finished download-and-extract step (success) 09:28:21 INFO - ##### 09:28:21 INFO - ##### 09:28:21 INFO - ##### Running populate-webroot step. 09:28:21 INFO - ##### 09:28:21 INFO - Running pre-action listener: _resource_record_pre_action 09:28:21 INFO - Running main action method: populate_webroot 09:28:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:28:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:28:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:28:21 INFO - '--tppagecycles', 09:28:21 INFO - '7'], 09:28:21 INFO - 'tests': ['tsvgm']}}, 09:28:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:28:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:28:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:28:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:28:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:28:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:28:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['damp', 'tps']}, 09:28:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'tests': ['damp', 'tps']}, 09:28:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['dromaeo_dom']}, 09:28:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:28:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['basic_compositor_video']}, 09:28:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:28:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'other-e10s': {'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['a11yr', 09:28:21 INFO - 'ts_paint', 09:28:21 INFO - 'tpaint', 09:28:21 INFO - 'sessionrestore', 09:28:21 INFO - 'sessionrestore_no_auto_restore', 09:28:21 INFO - 'tabpaint']}, 09:28:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['tsvgx', 09:28:21 INFO - 'tsvgr_opacity', 09:28:21 INFO - 'tart', 09:28:21 INFO - 'tscrollx', 09:28:21 INFO - 'cart']}, 09:28:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:28:21 INFO - 'tsvgr_opacity', 09:28:21 INFO - 'tart', 09:28:21 INFO - 'tscrollx', 09:28:21 INFO - 'cart']}, 09:28:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'talos_options': ['--disable-e10s'], 09:28:21 INFO - 'tests': ['tp5o']}, 09:28:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'tests': ['tp5o']}, 09:28:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'talos_options': ['--disable-e10s', 09:28:21 INFO - '--xperf_path', 09:28:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:28:21 INFO - 'tests': ['tp5n']}, 09:28:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:21 INFO - 'talos_options': ['--xperf_path', 09:28:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:28:21 INFO - 'tests': ['tp5n']}}, 09:28:21 INFO - 'talos.zip': {'path': '', 09:28:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:28:21 INFO - Running post-action listener: _resource_record_post_action 09:28:21 INFO - ##### 09:28:21 INFO - ##### Finished populate-webroot step (success) 09:28:21 INFO - ##### 09:28:21 INFO - ##### 09:28:21 INFO - ##### Running create-virtualenv step. 09:28:21 INFO - ##### 09:28:21 INFO - Running pre-action listener: _resource_record_pre_action 09:28:21 INFO - Running main action method: create_virtualenv 09:28:21 INFO - Creating virtualenv /builds/slave/test/build/venv 09:28:21 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:28:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:28:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:28:21 INFO - Using real prefix '/tools/python27' 09:28:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:28:22 INFO - Installing distribute.............................................................................................................................................................................................done. 09:28:24 INFO - Installing pip.................done. 09:28:24 INFO - Return code: 0 09:28:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:24 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:24 INFO - 'HOME': '/Users/cltbld', 09:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:24 INFO - 'LOGNAME': 'cltbld', 09:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:24 INFO - 'MOZ_NO_REMOTE': '1', 09:28:24 INFO - 'NO_EM_RESTART': '1', 09:28:24 INFO - 'PAGER': '/bin/cat', 09:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:24 INFO - 'PWD': '/builds/slave/test', 09:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:24 INFO - 'SHELL': '/bin/bash', 09:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:24 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:24 INFO - 'USER': 'cltbld', 09:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:24 INFO - 'XPC_FLAGS': '0x0', 09:28:24 INFO - 'XPC_SERVICE_NAME': '0', 09:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:28:25 INFO - Cleaning up... 09:28:25 INFO - Return code: 0 09:28:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:28:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:25 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:28:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:25 INFO - 'HOME': '/Users/cltbld', 09:28:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:25 INFO - 'LOGNAME': 'cltbld', 09:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:25 INFO - 'MOZ_NO_REMOTE': '1', 09:28:25 INFO - 'NO_EM_RESTART': '1', 09:28:25 INFO - 'PAGER': '/bin/cat', 09:28:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:25 INFO - 'PWD': '/builds/slave/test', 09:28:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:25 INFO - 'SHELL': '/bin/bash', 09:28:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:25 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:25 INFO - 'USER': 'cltbld', 09:28:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:25 INFO - 'XPC_FLAGS': '0x0', 09:28:25 INFO - 'XPC_SERVICE_NAME': '0', 09:28:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:25 INFO - Downloading/unpacking psutil>=3.1.1 09:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:28:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:28:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:28:28 INFO - Installing collected packages: psutil 09:28:28 INFO - Running setup.py install for psutil 09:28:28 INFO - building 'psutil._psutil_osx' extension 09:28:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:28:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:28:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:28:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:28:29 INFO - building 'psutil._psutil_posix' extension 09:28:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:28:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:28:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:28:29 INFO - ^ 09:28:29 INFO - 1 warning generated. 09:28:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:28:29 INFO - Successfully installed psutil 09:28:29 INFO - Cleaning up... 09:28:29 INFO - Return code: 0 09:28:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:28: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 mozsystemmonitor==0.3 09:28:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:29 INFO - 'HOME': '/Users/cltbld', 09:28:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:29 INFO - 'LOGNAME': 'cltbld', 09:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:29 INFO - 'MOZ_NO_REMOTE': '1', 09:28:29 INFO - 'NO_EM_RESTART': '1', 09:28:29 INFO - 'PAGER': '/bin/cat', 09:28:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:29 INFO - 'PWD': '/builds/slave/test', 09:28:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:29 INFO - 'SHELL': '/bin/bash', 09:28:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:29 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:29 INFO - 'USER': 'cltbld', 09:28:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:29 INFO - 'XPC_FLAGS': '0x0', 09:28:29 INFO - 'XPC_SERVICE_NAME': '0', 09:28:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:28:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:28:32 INFO - Installing collected packages: mozsystemmonitor 09:28:32 INFO - Running setup.py install for mozsystemmonitor 09:28:32 INFO - Successfully installed mozsystemmonitor 09:28:32 INFO - Cleaning up... 09:28:32 INFO - Return code: 0 09:28:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:32 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:32 INFO - 'HOME': '/Users/cltbld', 09:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:32 INFO - 'LOGNAME': 'cltbld', 09:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:32 INFO - 'MOZ_NO_REMOTE': '1', 09:28:32 INFO - 'NO_EM_RESTART': '1', 09:28:32 INFO - 'PAGER': '/bin/cat', 09:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:32 INFO - 'PWD': '/builds/slave/test', 09:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:32 INFO - 'SHELL': '/bin/bash', 09:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:32 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:32 INFO - 'USER': 'cltbld', 09:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:32 INFO - 'XPC_FLAGS': '0x0', 09:28:32 INFO - 'XPC_SERVICE_NAME': '0', 09:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:33 INFO - Downloading/unpacking jsonschema==2.5.1 09:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:28:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:28:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:28:35 INFO - warning: no files found matching '*.txt' 09:28:35 INFO - no previously-included directories found matching 'build' 09:28:35 INFO - no previously-included directories found matching 'dist' 09:28:35 INFO - no previously-included directories found matching '.git*' 09:28:35 INFO - Installing collected packages: jsonschema, functools32 09:28:36 INFO - Running setup.py install for functools32 09:28:36 INFO - warning: no files found matching '*.txt' 09:28:36 INFO - no previously-included directories found matching 'build' 09:28:36 INFO - no previously-included directories found matching 'dist' 09:28:36 INFO - no previously-included directories found matching '.git*' 09:28:36 INFO - Successfully installed jsonschema functools32 09:28:36 INFO - Cleaning up... 09:28:36 INFO - Return code: 0 09:28:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:28:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:28:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:36 INFO - 'HOME': '/Users/cltbld', 09:28:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:36 INFO - 'LOGNAME': 'cltbld', 09:28:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:36 INFO - 'MOZ_NO_REMOTE': '1', 09:28:36 INFO - 'NO_EM_RESTART': '1', 09:28:36 INFO - 'PAGER': '/bin/cat', 09:28:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:36 INFO - 'PWD': '/builds/slave/test', 09:28:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:36 INFO - 'SHELL': '/bin/bash', 09:28:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:36 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:36 INFO - 'USER': 'cltbld', 09:28:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:36 INFO - 'XPC_FLAGS': '0x0', 09:28:36 INFO - 'XPC_SERVICE_NAME': '0', 09:28:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:28:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:28:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:40 INFO - Downloading docopt-0.6.1.tar.gz 09:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:28:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:28:40 INFO - Running setup.py install for blobuploader 09:28:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:28:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:28:40 INFO - Running setup.py install for requests 09:28:40 INFO - Running setup.py install for docopt 09:28:41 INFO - Successfully installed blobuploader requests docopt 09:28:41 INFO - Cleaning up... 09:28:41 INFO - Return code: 0 09:28:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:41 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:28:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:41 INFO - 'HOME': '/Users/cltbld', 09:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:41 INFO - 'LOGNAME': 'cltbld', 09:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:41 INFO - 'MOZ_NO_REMOTE': '1', 09:28:41 INFO - 'NO_EM_RESTART': '1', 09:28:41 INFO - 'PAGER': '/bin/cat', 09:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:41 INFO - 'PWD': '/builds/slave/test', 09:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:41 INFO - 'SHELL': '/bin/bash', 09:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:41 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:41 INFO - 'USER': 'cltbld', 09:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:41 INFO - 'XPC_FLAGS': '0x0', 09:28:41 INFO - 'XPC_SERVICE_NAME': '0', 09:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-i5pDiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-W1aspe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gtUoC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zXBxKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Q3i8hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Ob3fsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:41 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-y5TQDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-SoLhLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-9Io8kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-FMRx94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OLF9ZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Zegh0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-m91Puu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-mR7Kp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-dru_H9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:42 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-oksg0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:43 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XpbCDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:28:43 INFO - Running setup.py install for manifestparser 09:28:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:28:43 INFO - Running setup.py install for mozcrash 09:28:43 INFO - Running setup.py install for mozdebug 09:28:43 INFO - Running setup.py install for mozdevice 09:28:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:28:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:28:43 INFO - Running setup.py install for mozfile 09:28:43 INFO - Running setup.py install for mozhttpd 09:28:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Running setup.py install for mozinfo 09:28:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Running setup.py install for mozInstall 09:28:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Running setup.py install for mozleak 09:28:44 INFO - Running setup.py install for mozlog 09:28:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Running setup.py install for moznetwork 09:28:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:28:44 INFO - Running setup.py install for mozprocess 09:28:44 INFO - Running setup.py install for mozprofile 09:28:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:28:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:28:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:28:45 INFO - Running setup.py install for mozrunner 09:28:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:28:45 INFO - Running setup.py install for mozscreenshot 09:28:45 INFO - Running setup.py install for moztest 09:28:45 INFO - Running setup.py install for mozversion 09:28:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:28:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:28:45 INFO - Cleaning up... 09:28:45 INFO - Return code: 0 09:28:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:45 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:45 INFO - 'HOME': '/Users/cltbld', 09:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:45 INFO - 'LOGNAME': 'cltbld', 09:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:45 INFO - 'MOZ_NO_REMOTE': '1', 09:28:45 INFO - 'NO_EM_RESTART': '1', 09:28:45 INFO - 'PAGER': '/bin/cat', 09:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:45 INFO - 'PWD': '/builds/slave/test', 09:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:45 INFO - 'SHELL': '/bin/bash', 09:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:45 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:45 INFO - 'USER': 'cltbld', 09:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:45 INFO - 'XPC_FLAGS': '0x0', 09:28:45 INFO - 'XPC_SERVICE_NAME': '0', 09:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:45 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-tG8qWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gFNpOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:46 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)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-c9neg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-BhQPTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:46 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)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OM10BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OMvKr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-oHrcSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-OyylJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-lx5goU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:28:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:46 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ieYef3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ljJY3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Qcw7K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-zbND55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-7JC8FO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5w3k0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ZwIT6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:47 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)) 09:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:47 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-nSwA_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:28:47 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)) 09:28:47 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)) 09:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:28:47 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)) 09:28:47 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)) 09:28:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:50 INFO - Downloading blessings-1.6.tar.gz 09:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:28:50 INFO - Installing collected packages: blessings 09:28:50 INFO - Running setup.py install for blessings 09:28:50 INFO - Successfully installed blessings 09:28:50 INFO - Cleaning up... 09:28:50 INFO - Return code: 0 09:28:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:28:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:50 INFO - Reading from file tmpfile_stdout 09:28:50 INFO - Current package versions: 09:28:50 INFO - blessings == 1.6 09:28:50 INFO - blobuploader == 1.2.4 09:28:50 INFO - docopt == 0.6.1 09:28:50 INFO - functools32 == 3.2.3-2 09:28:50 INFO - jsonschema == 2.5.1 09:28:50 INFO - manifestparser == 1.1 09:28:50 INFO - mozInstall == 1.12 09:28:50 INFO - mozcrash == 0.17 09:28:50 INFO - mozdebug == 0.1 09:28:50 INFO - mozdevice == 0.48 09:28:50 INFO - mozfile == 1.2 09:28:50 INFO - mozhttpd == 0.7 09:28:50 INFO - mozinfo == 0.9 09:28:50 INFO - mozleak == 0.1 09:28:50 INFO - mozlog == 3.2 09:28:50 INFO - moznetwork == 0.27 09:28:50 INFO - mozprocess == 0.22 09:28:50 INFO - mozprofile == 0.28 09:28:50 INFO - mozrunner == 6.11 09:28:50 INFO - mozscreenshot == 0.1 09:28:50 INFO - mozsystemmonitor == 0.3 09:28:50 INFO - moztest == 0.7 09:28:50 INFO - mozversion == 1.4 09:28:50 INFO - psutil == 3.1.1 09:28:50 INFO - requests == 1.2.3 09:28:50 INFO - wsgiref == 0.1.2 09:28:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:50 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 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:50 INFO - 'HOME': '/Users/cltbld', 09:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:50 INFO - 'LOGNAME': 'cltbld', 09:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:50 INFO - 'MOZ_NO_REMOTE': '1', 09:28:50 INFO - 'NO_EM_RESTART': '1', 09:28:50 INFO - 'PAGER': '/bin/cat', 09:28:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:50 INFO - 'PWD': '/builds/slave/test', 09:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:50 INFO - 'SHELL': '/bin/bash', 09:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:50 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:50 INFO - 'USER': 'cltbld', 09:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:50 INFO - 'XPC_FLAGS': '0x0', 09:28:50 INFO - 'XPC_SERVICE_NAME': '0', 09:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:28:51 INFO - Cleaning up... 09:28:51 INFO - Return code: 0 09:28:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104340618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcec9cc7350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, '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.CtV00UKEtE/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.LuUAXMVArA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:51 INFO - 'HOME': '/Users/cltbld', 09:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:51 INFO - 'LOGNAME': 'cltbld', 09:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:51 INFO - 'MOZ_NO_REMOTE': '1', 09:28:51 INFO - 'NO_EM_RESTART': '1', 09:28:51 INFO - 'PAGER': '/bin/cat', 09:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:51 INFO - 'PWD': '/builds/slave/test', 09:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:51 INFO - 'SHELL': '/bin/bash', 09:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:28:51 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:51 INFO - 'USER': 'cltbld', 09:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:51 INFO - 'XPC_FLAGS': '0x0', 09:28:51 INFO - 'XPC_SERVICE_NAME': '0', 09:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:28:51 INFO - Cleaning up... 09:28:51 INFO - Return code: 0 09:28:51 INFO - Running post-action listener: _resource_record_post_action 09:28:51 INFO - Running post-action listener: _start_resource_monitoring 09:28:51 INFO - Starting resource monitoring. 09:28:51 INFO - ##### 09:28:51 INFO - ##### Finished create-virtualenv step (success) 09:28:51 INFO - ##### 09:28:51 INFO - ##### 09:28:51 INFO - ##### Running install step. 09:28:51 INFO - ##### 09:28:51 INFO - Running pre-action listener: _resource_record_pre_action 09:28:51 INFO - Running main action method: install 09:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:28:51 INFO - Reading from file tmpfile_stdout 09:28:51 INFO - Detecting whether we're running mozinstall >=1.0... 09:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:28:51 INFO - Reading from file tmpfile_stdout 09:28:51 INFO - Output received: 09:28:51 INFO - Usage: mozinstall [options] installer 09:28:51 INFO - Options: 09:28:51 INFO - -h, --help show this help message and exit 09:28:51 INFO - -d DEST, --destination=DEST 09:28:51 INFO - Directory to install application into. [default: 09:28:51 INFO - "/builds/slave/test"] 09:28:51 INFO - --app=APP Application being installed. [default: firefox] 09:28:51 INFO - mkdir: /builds/slave/test/build/application 09:28:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:29:26 INFO - Reading from file tmpfile_stdout 09:29:26 INFO - Output received: 09:29:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:29:26 INFO - Running post-action listener: _resource_record_post_action 09:29:26 INFO - ##### 09:29:26 INFO - ##### Finished install step (success) 09:29:26 INFO - ##### 09:29:26 INFO - ##### 09:29:26 INFO - ##### Running run-tests step. 09:29:26 INFO - ##### 09:29:26 INFO - Running pre-action listener: _resource_record_pre_action 09:29:26 INFO - Running main action method: run_tests 09:29:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:29:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:29:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:29:26 INFO - 2016-06-28 09:29:26.727 screenresolution[1641:7500] starting screenresolution argv=screenresolution get 09:29:26 INFO - 2016-06-28 09:29:26.730 screenresolution[1641:7500] Display 0: 1600x1200x32@60 09:29:26 INFO - 2016-06-28 09:29:26.735 screenresolution[1642:7502] starting screenresolution argv=screenresolution list 09:29:26 INFO - Available Modes on Display 0 09:29:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:29:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:29:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:29:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:29:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:29:26 INFO - Intel Iris: 09:29:26 INFO - Chipset Model: Intel Iris 09:29:26 INFO - Type: GPU 09:29:26 INFO - Bus: Built-In 09:29:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:29:26 INFO - Vendor: Intel (0x8086) 09:29:26 INFO - Device ID: 0x0a2e 09:29:26 INFO - Revision ID: 0x0009 09:29:26 INFO - Displays: 09:29:26 INFO - Display: 09:29:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:29:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:29:26 INFO - Display Serial Number: 1600x1200 60 09:29:26 INFO - Main Display: Yes 09:29:26 INFO - Mirror: Off 09:29:26 INFO - Online: Yes 09:29:26 INFO - Rotation: Supported 09:29:26 INFO - Return code: 0 09:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:29:26 INFO - Python 2.7.3 09:29:26 INFO - Return code: 0 09:29:26 INFO - grabbing minidump binary from tooltool 09:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:26 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 0x10420fd30>, '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 0x103f74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104207b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:29:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:29:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:29:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:29:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:29:27 INFO - Return code: 0 09:29:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:29:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:29:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:29:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:29:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:29:27 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/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 09:29:27 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/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 09:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CtV00UKEtE/Render', 09:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:27 INFO - 'HOME': '/Users/cltbld', 09:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:27 INFO - 'LOGNAME': 'cltbld', 09:29:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:29:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:27 INFO - 'MOZ_NO_REMOTE': '1', 09:29:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:29:27 INFO - 'NO_EM_RESTART': '1', 09:29:27 INFO - 'PAGER': '/bin/cat', 09:29:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:27 INFO - 'PWD': '/builds/slave/test', 09:29:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:27 INFO - 'SHELL': '/bin/bash', 09:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LuUAXMVArA/Listeners', 09:29:27 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 09:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:27 INFO - 'USER': 'cltbld', 09:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:27 INFO - 'XPC_FLAGS': '0x0', 09:29:27 INFO - 'XPC_SERVICE_NAME': '0', 09:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:27 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/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 09:29:27 INFO - mozversion application_buildid: 20160628071022 09:29:27 INFO - mozversion application_changeset: e45890951ce77c3df05575bd54072b9f300d77b0 09:29:27 INFO - mozversion application_display_name: Nightly 09:29:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:29:27 INFO - mozversion application_name: Firefox 09:29:27 INFO - mozversion application_remotingname: firefox 09:29:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:29:27 INFO - mozversion application_vendor: Mozilla 09:29:27 INFO - mozversion application_version: 50.0a1 09:29:27 INFO - mozversion platform_buildid: 20160628071022 09:29:27 INFO - mozversion platform_changeset: e45890951ce77c3df05575bd54072b9f300d77b0 09:29:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:29:27 INFO - mozversion platform_version: 50.0a1 09:29:27 INFO - using testdate: 1467131367 09:29:27 INFO - actual date: 1467131367 09:29:27 INFO - starting webserver on 'localhost:49266' 09:29:27 INFO - SUITE-START | Running 2 tests 09:29:27 INFO - TEST-START | dromaeo_css 09:29:27 INFO - operating with platform_type : mac_ 09:29:27 INFO - Initialising browser for dromaeo_css test... 09:29:27 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpO307x_/profile http://localhost:49266/getInfo.html) 09:29:29 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:29:29 INFO - PROCESS | 1652 | colorDepth:24 09:29:29 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:29:29 INFO - PROCESS | 1652 | __metrics 09:29:29 INFO - PROCESS | 1652 | 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" 09:29:29 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:29:30 INFO - TEST-INFO | 1652: exit 0 09:29:30 INFO - Browser initialized. 09:29:30 INFO - Running cycle 1/1 for dromaeo_css test... 09:29:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpO307x_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:42 INFO - PROCESS | 1654 | RSS: Main: 243527680 09:29:42 INFO - PROCESS | 1654 | 09:30:34 INFO - PROCESS | 1654 | 1467131434082 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] 09:32:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 09:32:45 INFO - PROCESS | 1654 | RSS: Main: 340865024 09:32:45 INFO - PROCESS | 1654 | 09:35:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 09:35:49 INFO - PROCESS | 1654 | RSS: Main: 379179008 09:35:49 INFO - PROCESS | 1654 | 09:38:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 09:38:52 INFO - PROCESS | 1654 | RSS: Main: 373747712 09:38:52 INFO - PROCESS | 1654 | 09:41:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 09:41:55 INFO - PROCESS | 1654 | RSS: Main: 374226944 09:41:55 INFO - PROCESS | 1654 | 09:44:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 09:44:59 INFO - PROCESS | 1654 | RSS: Main: 342917120 09:44:59 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 09:48:03 INFO - PROCESS | 1654 | RSS: Main: 398639104 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | __start_tp_report 09:48:03 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:48:03 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:48:03 INFO - PROCESS | 1654 | |i|pagename|runs| 09:48:03 INFO - PROCESS | 1654 | |0;dojo.html;3371.8145501997396;3380.79715217087;3406.2506248625295;3419.076849250703;3439.6216416194216;5055.710793747594;5636.802711905084;5648.830535083949;5725.770969161229;5773.336066352371;5800.738966746496;5803.883922321562;5945.70271486425;5955;5983.9102413463725;5176.731700733307;5196.402413722413;5271.578273738091;5277.577793776489;5337.626366154371;5329.920051199216;5390;5402.9729851350885;5412.296401467804;5436.673799572019;5166.225066240076;5215.24378965048;5224.92162617561;5284.947150528484;5293.708846013467;5204.9219261711105;5224.242484839677;5227.555657769094;5255.76349064293;5258.684478931276;5199.402068762103;5253.816116435906;5284.5243928046275;5323.653962492425;5345.091334473149;5643.6049476536655;5705.515031222351;5868.794592189253;5939.613925094855;5952.851178720567;5622.8313150605545;6046.365131661156;6125.938740612582;6133.325334213233;6140.13616995768;6193.380661933816;6245.656488893109;6255.24937007562;6255.812325630239;6268.09112678659;5817.825465236049;6051.425114614105;6067.120267561223;6072.967595508774;6079.817605471843;6210.9068363974575;6239.251289845248;6300.369963003663;6324.146240257506;6401.391867772554;6103.057498482739;6104.633721976696;6115.388461153849;6130.474976888307;6130.478909292671;6115.694215289218;6136.754529818757;6186.133941248228;6264.498840092781;6266.530010249158;5848.298204215522;5887.882242355215;6106.430331452215;6116.092308610896;6166.630002199882;6121.84724866694;6150.04674275488;6163.96918015407;6243.25080990275;6250.343713910065;4973.47778483254;4983.451820299764;4983.551480366785;4986.900261994812;5014.197728363444;4400.471943366816;4404.955950440519;4436.068425630589;4509.549045095464;4511.593956543927;5766;5804.4775970162045;5821.563382746226;5876.294844618673;5971.164037034819;6232.345603711636;6245.500359971192;6254.15568898202;6263;6278.466330361879;5824.097264923944;5879.823605291848;5909.3204281507315;5914.319853216849;5930.644161350333;5345.33183352081;5401.972990135024;5421.484958928974;5480.04099282624;5481.232627432163;6335.524835637346;6335.778247761306;6364.554481186365;6366.522510811615;6368.840778980562;6196.907046394398;6211.565190436807;6223.751049957951;6239.78160764371;6257.937420625735;6200.720967556561;6228.03465462854;6239.844003899939;6286.8428289291305;6316.368363163647;6240.157578726814;6243.500519958483;6263.123162757127;6264.498840092872;6268.780592679234;4876.463589005278;6107.931112055461;6231.563790534619;6244.5316601256;6279.277883043325;5091.974540127276;6078.878422431615;6132.569672417391;6145.647957449354;6229.937700622936;6152.815415537541;6179.660118693515;6184.319724830355;6185.381461853779;6234.719437625408;5821.155932389695;6058.818235452944;6082.321852465646;6104.542159338156;6108.358622344767;6128.662923539248;6179.196704428396;6184.5052395809125;6241.656708881055;6253.312135664983;6070.362611925684;6083.452489275986;6091.994820854687;6108.541859360477;6122.815596962102 09:48:03 INFO - PROCESS | 1654 | |1;ext.html;488.49440828742235;491.83523519620906;513.9797501958612;521.9660722053064;529.5604648142042;31681.841590792043;42715.57284427155;42852.57147428525;42898.99999999996;44660;43178.56821431785;43505.12989740203;43522.347164792554;43701.78149109258;43731;8434.789130271762;8711.564421778918;8939.418937769024;9623.951880240624;9789.853152202722;11794.646160615195;12030;12044.976177024964;12152.39238038099;12172.93913530431;11921;11996.280223186637;12011.519539218421;12023.218490798114;12034.63896083119;8813.07462716412;11809.586664466702;11904.285742855413;12045.397730113547;12062.457189426497;11592.826107608393;11650.941745291218;11653.766924661544;11665.183437159401;11667.183297169202;34360.48459273113;41294;41635.583644163475;41665.37501937473;41739;3145.874165033396;3305.7107727986104;3407.6251612322626;3418.025862629139;3426.4860270959307;31831;32069.877554285722;32586.837065814758;32792.836035819666;33255.667443325496;3244.8864289749745;3311.718503927169;3320.335932813447;3385.0352649494776;3393.100828280508;454988;515802;520584;524875;525203;34314.82842585771;56792.14811777827;57006;58045.41954580485;65150.67424662846;34823.65176348255;35897.820510897276;35920.640793592254;35956;35965.82017089898;14981.40074397012;15098.773518397233;15170.165640889856;15171.620709482351;15195.696086078438;10434.68695939123;11065.114790816715;11421.600243991421;11624;11861.525538978343;7406.444516661272;7629.771106866692;7825.491343062797;7833.09919359281;7851.254130857559;3315.950260746091;3327.9168020799675;3330.1508115430893;3340.6993370596765;3376.594808622981;928.1831987850783;984.7291994701371;990.5195979949863;990.6928852055886;1082.8105081610686;30299;30833.845830770704;30856.53715194274;31103.84448077745;31333.06000819979;1085.46812062089;1104.7514309280348;1148.529103067755;1170.3387586014046;1173.4074292482242;15756.212189390484;15988.520344389686;15989.040657560583;16062.839371606367;16118.435854745028;692408;700875;702107.4894625494;703432;704096;47295.763521182176;60211;60424.697876510334;60438;60446;1162.3084264862393;1193.1111322064944;1338.491373278148;1407.978880316796;1421.133108803629;29690.554641680825;29967.384667318434;31701.5244771324;31803.68196318007;32154.356912861145;50733.23900141575;55895.72052139713;56197.87604247974;56633.43366566447;59656.80686386335;636140;641879;648207;648709;651041.4895850981;36018.09954751152;36184.81907590445;43338.13323733445;43821.904452388946;44620;45189.54810451854;47950;48439.75780121218;49457.752711237656;50604;40009.399859001554;40217.1956560873;40452.25503268613;41378.793106034274;41511.58488415198;38412.23175536418;39086.804565976985;39463.80268098756;39930.80034599808;40055.1988960225;38073.80963095167;38247;38763;38867.6113238864;38916.027099322746;32303.192420189684;32601.34797303993;34331.65668343285;34392;34907.65092349044;25579.360515986504;25630;25817.354566135997;26001.219963401127;26324.736752632994 09:48:03 INFO - PROCESS | 1654 | |2;jquery.html;380.8457574682253;384.5788861196989;390.18840811112887;402.73419543101556;412.6801728660288;19718.605627887435;20108.4972875678;21451.892740536296;21471.355859324205;22487;20025;21068.367948961517;21147.894260528716;21344.57310853782;21819.01814418351;5202.751339678469;5867.970660146693;6016.548758843082;6024.2770867495965;6032.638041717511;3849;4117;4697.67116301859;5829.912551311733;6097.237845269341;6120.755169793203;6127.877442451148;6139.815805525842;6160.5687601867885;6165.136880836686;5563;5850.444207800273;6126;6132.816015519542;6152.107944348089;3780.451834484012;4013.8394464221687;4055.6755459563165;5888.911666325009;5912.4974377178;25323.493530129294;26069.478610427876;26113;26516.86741566299;26525.867370663214;1938.932137375199;2114.217739436403;2145.324222869791;2166.9133234670594;2917.8182835951475;9659.372140810896;9978.70063898077;10641.680749577525;10644.361338319726;10677.30597511167;1550.8139023317162;1946.2896042944365;1993.5414854583382;2038.7451568553931;2050.261905713942;113440.89515971801;122881;125014.37492812569;125359;126216.36891815574;11225.214235003472;11645.243059201124;11662.88337116635;11698.70753231176;11888.583899563473;14855.034422762703;14964.551063468114;14979.951403401657;14980.925095374454;15016.549503514912;6925.926481395391;8586.785330366665;8633.827323453621;8666.306695464475;8715.084916083846;6998;8520.488770673779;8528.573571321409;8623;8663.090375510606;4523.072770082125;4615.376924115269;4623.676342655982;4656.8602941911795;4692.929606055912;1563.3277690592927;1813.165943665902;1823.7925175964551;1898.620275944789;1973.6940774180025;943;980.4705459052032;984.8818141823027;999.9150072243796;1009.3489699144158;22106.894655267173;22361.329160125224;22696.319110426713;22696.886515567316;22786.08855645789;939.2157548446986;942.330945029037;949.2738055387634;971.6842026341467;972.4214092614883;11408.087353011739;11741.647750567497;11802.232854864578;11822.290662560274;11874.881251187551;550289;561225.5203411847;562007;568923.3984470709;586288;124884;130872;132901;133384.9992250117;136101.3194934019;957.200737384278;966.21253678252;978.5205249427871;979.4172467381896;1014.3508154781093;19346.806531935064;19601.70597441068;19677.901610491856;19724.112414941075;19795.406137815313;50409.99180016453;52417.95164096773;53505.7324713374;53708.731456342466;54449.455505444435;70603.64698176476;82315;84451;85466;93578.53210733902;5441.5102640762525;6266.968665156827;6822.897656535057;7324.828027515572;8244.29923456513;11734.647960561197;12858.871411285767;12943.935280323914;13210.405531751287;13266.00504962151;10706.839397408889;11346.489407976826;11519.827202592136;11597.13021523406;11600.24598401135;10988.230823842574;11320.867913208613;11371.772564548828;11515.53937842477;11572.305661660328;11501.482433290355;11618.70953226176;11651.533938642699;11805.409729513662;11833.704157396134;8145.470544414831;8245.216704413071;8280.171982801672;8413.200745929127;8513.19124683154;9652.710418687451;11003.671543792232;11615.88384116148;11644.475998579916;11873.940630296793 09:48:03 INFO - PROCESS | 1654 | |3;mootools.html;1383.4120498788013;1539.9538013859585;1563.5543869997052;1803.4679769468005;1987.2547794577033;7825.804354891131;8551.059383467822;8812.250958668514;8822.823543529126;8943.150400711938;8593.656253749858;8657.653693852255;8733.91266087339;8744.737657870273;8752.124787521245;2070.2754036087326;2160;2343.3087239264373;2378.2746262389965;2379.4408314046186;2178.8474806763534;2201.9009144588454;2204.8787316697576;2310.7111611048617;2399.8200134989856;2357.7760112789256;2364.2316247219637;2369.218158007862;2371.6561098640686;2374.465745207332;2318.455163036685;2326.848754830939;2354.0701422938014;2354.4937838364835;2356.787889089973;1936.8741031832885;1952.8535359848067;2265.1392470861133;2342.953140937189;2348.1781376518084;8705.216530512283;8774.473531588124;8797.032326444087;8803.603837827313;8812.295016398673;5185.429602743695;5355.196720491918;5363.168708850135;5369.677819330853;5370.275012873251;3061.11227743954;3485.4597537381746;3592.7125829933543;3662.285854258394;3663.230721548478;1076.6662334676275;1087.6682611803396;1099.120703437247;1119.2333251722596;1119.5018216893486;11638.709032274177;11653.941730291379;11659.883401165964;11677.357745324003;11680.649580512598;4612.815487380534;4628.583427491474;4785.1625965455905;4789.784459699322;4790.880227994328;9883.258755593359;9893.65372211969;9912.058354456316;9918.950405247928;9932.155766759763;5340.893182136336;5356.143017117242;5363.463653634683;5368;5368.194770784414;4724.574788269004;4836.081144582518;4904.337914381585;4910.950890491121;4955.578775804025;3549.1304630365726;3707.740458167956;3776.4712940188397;3860.1700634363747;3920.353141731582;1163.1450883600546;1163.4241050679968;1165.8018136916744;1177.14068729826;1178.8526434195726;920.397139873384;927.9164875161268;933.8879334479906;938.2681508423441;976.1800087926176;4966.701997880139;5905.675187864708;5979.252593425822;5986.970065149646;6142.806098141948;965.3628605120728;969.4427682537175;973.4889183178888;987.3286165407449;1006.8036732837027;3895.53253609563;4016.8995775105855;4071.6742660587083;4083.3466645336603;4085.428040074392;8994.820103597891;10069.79860402788;10765;10774.407407592658;10784.56861725538;10062.597496100074;10618.469076546142;10715.785684286271;10741.462926853783;10743.033127018605;7845.137034926153;8210.014798224132;8229.012518497699;8234;8246.8350632987;5997.100434934795;6045.274567051982;6051.12258722474;6166.722497487534;6339;9409.811803763749;9657.613695452103;10088.798224035625;10096.69709908704;10101.44442055694;9765.21878249724;10177.796444071226;10216.029477199654;10231.181505479395;10260.846087308846;8086.272235498834;8167.346612270885;8280.254777070093;8299.502029878227;8318.83362332762;7044.542104763174;7570.05374328209;7884.566348850868;8030.759077227693;8333.749987500385;7432.777016689508;7510.47426680149;7546.849063018819;7547.207543208104;7554.962225188839;7431.8142046449275;7461.925380746123;7511.32398084175;7521.548707077593;7533.472656913963;6407.807765767035;6838.760643377458;6971.825704357431;7105.218425973242;7140.07179066718;5010.974945125251;5053.646244762831;5115.18157094863;5127.6410651254055;5197.376314842092;4139.97930010348;5072.644914856072;5169.844904652867;5178.275041494269;5206.5314121729225 09:48:03 INFO - PROCESS | 1654 | |4;prototype.html;307.0649871142371;310.7917695144254;313.79760054764677;317.14687490650164;320.1260558674819;4513.277875539914;5205.739713014344;5281.656692314997;5296.523312901838;5303;4367.257566213743;4882;5189.091908915943;5231.895362092756;5254.344881362368;3050.7254347108756;3419.4870769384543;3886.4753258310047;4052.5136983562015;4061.5735347788354;3538.82305884706;3975.7813320267373;4085.0400155963325;4102.79486025699;4113.3957756475;3426.828658567074;4041.292773764594;4043.3126368517424;4055.0065234017707;4085.407615895693;3491.202030954048;3832.2143960488033;4003.319435695939;4061.776602286873;4102.5692302308125;3110.4401207782817;3155.857986390618;3532.7703699259464;3541.1501239702543;3561.2343346180437;4863.53796389346;5253.058287359053;5269.5484457679895;5273.679580984354;5278.604104692164;4498.8425405110665;4671.089137618166;4677.204875171229;4677.438707355105;4712.293156026589;4817.51824817519;5607.158926161109;5624;5634.549236061146;5703.144528320744;3342.632310445849;3880.902977425587;3881.573026967031;3927.9018024549327;3934.5081864766903;5631.18347839561;5881.411858814127;5937.910931336033;5962.224910761616;5976.581639285252;3273.3289675616443;3890.5914878937306;3901.49280593521;3906;3913.5890731472787;3916.9815847879186;3960.7623542587535;3974.6621537169085;4003.839846406111;4018.618231268025;3818.3653279237237;4007.5391329997424;4606.742696078897;4640.9535904640525;4652.023075154188;5522.459668552511;5581.795190899653;5593.300837395325;5599.552035837123;5640.717964101778;1337.4783834304628;1345.2533843716635;1364.379207460603;1368.8220531330862;1372.8673844078676;834.845553572591;849.3205435651454;851.7742798158492;866.6706651472526;892.8705337725993;4632.768361581975;5032.094223039815;5145.842185508231;5216.321878155815;5327.547158491494;5104.498795590208;5193.675612718733;5701.971490142523;5744.798932037359;5758.798442054508;4438.800253988578;4856.368672072608;4900.275790399322;4937.950620493821;5260.342457192851;3019.5923550320426;3042.9239269018453;3059.831709255968;3071.4778487656704;3080.3531258435532;5491.588130890119;5595.38450770415;5966.866295403859;5996.342231238941;6092.573519853568;4763.809447622126;5774.047282198474;5894.587378883437;5908.763649454059;5908.822735317947;5283.094298136087;5882;5886.381929897299;5950.9702451487465;6008.8197354078575;5147.613928955268;5466;5717.485426311652;5810.244668193108;5863.384344643751;4905.460399355997;4921.532454416824;5337.706426146444;5481.780728770805;5656.547476201977;4866.683665561727;5835.44563266506;5940.28716553999;5954.409831723215;5997.13041608973;2928.1215635308895;3204.8077115373153;3331.06730115568;3399.9320013600086;3407.625161232213;4546.340196142138;4641.6518761092375;4935.716713654548;4966.925496117633;4978.775955081957;4348.043430445297;4356.847510337123;4415.9116817662825;4817.349657796293;4860.659753817199;4128.967758060485;4271.807768650356;4581.5647513486165;4701.9529804701515;5143;4792.017636384602;4955.8017679292425;5473.907242408027;5542.639728417801;5613.859653508695;5318.4149743527405;5377.516023557899;5521.889562208653;5555.30558680165;5627.352854421794;4577.862664120082;5531.446855314436;5532.723363831873;5575.944240557543;5675.858103547444 09:48:03 INFO - PROCESS | 1654 | |5;yui.html;685.5715178013742;707.4022451028878;768.7078910014194;811.1482942909943;838.8112674648205;14963.251837408126;15666.530004099866;15684.921575392122;15693.136877471734;15699.764503532442;12602.873971260307;12619.24284542928;12622.431990560424;13021.73956520869;14556.927215363934;712.5760172697263;901.5176880369004;917.7567944494714;918.2332752151927;919.7286800393866;731.6926890705889;891.1445012787699;923.6859467780953;924.0113079005467;924.5654542365111;968.099667309402;971.2715463402449;972.3436680240809;973.4110862927932;975.1126474907835;686.6507313129724;724.9637518124125;963.6964356227766;964.8793900762405;968.6367612145445;696.9163700355939;710.6127160697432;710.8187412209905;717.7954283029363;718.392957950531;15310.081395116218;15443.768343474856;15742.685146297124;15758;15768.842311576967;13973;14075.929620351935;14114.647133821634;14261.643458913506;14332.06841555306;12758.234505929673;12785.10504264702;12808.23150610957;12981.870181298254;13323.467061317535;4615.330777037344;4649.721016738973;4668.206404911173;4777.713337199779;4835.806567737286;84122.57938710267;109692;111460;111673;112727.99999999919;10549.20880933933;10566.996135367128;11346.716332091599;11430.371329576787;11990.580329688419;19264.422067338;22910.42723931882;23251.18620848262;23388.883055584614;23645.527089458457;8038.678452861937;8068;8123.228293312126;8219.8356032879;8355.122712115262;4373.75944323059;4402.097569998143;4470.865874023784;4481.238189507726;4507.2788353863225;3512.8067956262653;3633.454981752758;3634.200475895299;3636.2727454508677;3640.3629364861044;599.8033922324989;640.5836206465741;663.6416335179046;670.1924181361447;671.5467059734659;628.6510986402591;640.2828831708516;640.6674117834986;653.5294587896707;673.7877568565887;7399.59302238382;8706.956465217634;8743.431676940978;8774.385792994557;8915.552553686019;577.206341280739;588.1824264272665;638.7349250061266;645.7675236914707;646.7815393201271;4348.804303806273;4353.69524133314;4388.29787234041;4397.670174736908;4471.888202794956;150359;153334;155580;157184;160868;83799;132003.01995470075;134207;134632;137500;84631;89998.55000724955;107664.46167769424;108231.91768082218;109510;7968.402369822171;8216.630251638571;8431.95784021076;8486;8627.95686021566;62226.37773622387;63524.74178356969;64116.35883641104;64318.678406607665;66280.6685966567;82710.58644706738;90585.54707226422;96570.51714741382;97883;99918.5004074975;11557.88442115568;11738.58914937973;11777.705557361134;11778.116641251769;11884.643460696192;12038.518459261531;12076.577319793765;12166.939165304117;12733.172343797623;12767.297798621164;11592.478338474957;11736;11830.763384732085;11940.820887686865;12062.879371206176;11324.94337528307;11469.655910322703;11770;11833.940830295793;12180.75638487243;11567.768644626893;11570.768584628091;12108.818367724667;12114.454849531969;12133.150679452707;8593.31253499731;8629.69796057135;8675.652973880975;8712.649266504264;8753.956230218808;6403.583767055313;8484.151584841466;8537.74386768398;8660.913390866011;8692.739217823475 09:48:03 INFO - PROCESS | 1654 | __end_tp_report 09:48:03 INFO - PROCESS | 1654 | __start_cc_report 09:48:03 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1210 09:48:03 INFO - PROCESS | 1654 | __end_cc_report 09:48:03 INFO - PROCESS | 1654 | __startTimestamp1467132483258__endTimestamp 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:48:03 INFO - PROCESS | 1654 | Number of tests: 6 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5773.74 Median:6072.97 stddev:630.77 (10.4%) stddev-sans-first:603.91 09:48:03 INFO - PROCESS | 1654 | Values: 3371.8 3380.8 3406.3 3419.1 3439.6 5055.7 5636.8 5648.8 5725.8 5773.3 5800.7 5803.9 5945.7 5955.0 5983.9 5176.7 5196.4 5271.6 5277.6 5337.6 5329.9 5390.0 5403.0 5412.3 5436.7 5166.2 5215.2 5224.9 5284.9 5293.7 5204.9 5224.2 5227.6 5255.8 5258.7 5199.4 5253.8 5284.5 5323.7 5345.1 5643.6 5705.5 5868.8 5939.6 5952.9 5622.8 6046.4 6125.9 6133.3 6140.1 6193.4 6245.7 6255.2 6255.8 6268.1 5817.8 6051.4 6067.1 6073.0 6079.8 6210.9 6239.3 6300.4 6324.1 6401.4 6103.1 6104.6 6115.4 6130.5 6130.5 6115.7 6136.8 6186.1 6264.5 6266.5 5848.3 5887.9 6106.4 6116.1 6166.6 6121.8 6150.0 6164.0 6243.3 6250.3 4973.5 4983.5 4983.6 4986.9 5014.2 4400.5 4405.0 4436.1 4509.5 4511.6 5766.0 5804.5 5821.6 5876.3 5971.2 6232.3 6245.5 6254.2 6263.0 6278.5 5824.1 5879.8 5909.3 5914.3 5930.6 5345.3 5402.0 5421.5 5480.0 5481.2 6335.5 6335.8 6364.6 6366.5 6368.8 6196.9 6211.6 6223.8 6239.8 6257.9 6200.7 6228.0 6239.8 6286.8 6316.4 6240.2 6243.5 6263.1 6264.5 6268.8 4876.5 6107.9 6231.6 6244.5 6279.3 5092.0 6078.9 6132.6 6145.6 6229.9 6152.8 6179.7 6184.3 6185.4 6234.7 5821.2 6058.8 6082.3 6104.5 6108.4 6128.7 6179.2 6184.5 6241.7 6253.3 6070.4 6083.5 6092.0 6108.5 6122.8 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:74007.13 Median:35938.32 stddev:167125.45 (465.0%) stddev-sans-first:167503.13 09:48:03 INFO - PROCESS | 1654 | Values: 488.5 491.8 514.0 522.0 529.6 31681.8 42715.6 42852.6 42899.0 44660.0 43178.6 43505.1 43522.3 43701.8 43731.0 8434.8 8711.6 8939.4 9624.0 9789.9 11794.6 12030.0 12045.0 12152.4 12172.9 11921.0 11996.3 12011.5 12023.2 12034.6 8813.1 11809.6 11904.3 12045.4 12062.5 11592.8 11650.9 11653.8 11665.2 11667.2 34360.5 41294.0 41635.6 41665.4 41739.0 3145.9 3305.7 3407.6 3418.0 3426.5 31831.0 32069.9 32586.8 32792.8 33255.7 3244.9 3311.7 3320.3 3385.0 3393.1 454988.0 515802.0 520584.0 524875.0 525203.0 34314.8 56792.1 57006.0 58045.4 65150.7 34823.7 35897.8 35920.6 35956.0 35965.8 14981.4 15098.8 15170.2 15171.6 15195.7 10434.7 11065.1 11421.6 11624.0 11861.5 7406.4 7629.8 7825.5 7833.1 7851.3 3316.0 3327.9 3330.2 3340.7 3376.6 928.2 984.7 990.5 990.7 1082.8 30299.0 30833.8 30856.5 31103.8 31333.1 1085.5 1104.8 1148.5 1170.3 1173.4 15756.2 15988.5 15989.0 16062.8 16118.4 692408.0 700875.0 702107.5 703432.0 704096.0 47295.8 60211.0 60424.7 60438.0 60446.0 1162.3 1193.1 1338.5 1408.0 1421.1 29690.6 29967.4 31701.5 31803.7 32154.4 50733.2 55895.7 56197.9 56633.4 59656.8 636140.0 641879.0 648207.0 648709.0 651041.5 36018.1 36184.8 43338.1 43821.9 44620.0 45189.5 47950.0 48439.8 49457.8 50604.0 40009.4 40217.2 40452.3 41378.8 41511.6 38412.2 39086.8 39463.8 39930.8 40055.2 38073.8 38247.0 38763.0 38867.6 38916.0 32303.2 32601.3 34331.7 34392.0 34907.7 25579.4 25630.0 25817.4 26001.2 26324.7 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34631.62 Median:26314.93 stddev:95046.51 (361.2%) stddev-sans-first:95278.35 09:48:03 INFO - PROCESS | 1654 | Values: 380.8 384.6 390.2 402.7 412.7 19718.6 20108.5 21451.9 21471.4 22487.0 20025.0 21068.4 21147.9 21344.6 21819.0 5202.8 5868.0 6016.5 6024.3 6032.6 3849.0 4117.0 4697.7 5829.9 6097.2 6120.8 6127.9 6139.8 6160.6 6165.1 5563.0 5850.4 6126.0 6132.8 6152.1 3780.5 4013.8 4055.7 5888.9 5912.5 25323.5 26069.5 26113.0 26516.9 26525.9 1938.9 2114.2 2145.3 2166.9 2917.8 9659.4 9978.7 10641.7 10644.4 10677.3 1550.8 1946.3 1993.5 2038.7 2050.3 113440.9 122881.0 125014.4 125359.0 126216.4 11225.2 11645.2 11662.9 11698.7 11888.6 14855.0 14964.6 14980.0 14980.9 15016.5 6925.9 8586.8 8633.8 8666.3 8715.1 6998.0 8520.5 8528.6 8623.0 8663.1 4523.1 4615.4 4623.7 4656.9 4692.9 1563.3 1813.2 1823.8 1898.6 1973.7 943.0 980.5 984.9 999.9 1009.3 22106.9 22361.3 22696.3 22696.9 22786.1 939.2 942.3 949.3 971.7 972.4 11408.1 11741.6 11802.2 11822.3 11874.9 550289.0 561225.5 562007.0 568923.4 586288.0 124884.0 130872.0 132901.0 133385.0 136101.3 957.2 966.2 978.5 979.4 1014.4 19346.8 19601.7 19677.9 19724.1 19795.4 50410.0 52418.0 53505.7 53708.7 54449.5 70603.6 82315.0 84451.0 85466.0 93578.5 5441.5 6267.0 6822.9 7324.8 8244.3 11734.6 12858.9 12943.9 13210.4 13266.0 10706.8 11346.5 11519.8 11597.1 11600.2 10988.2 11320.9 11371.8 11515.5 11572.3 11501.5 11618.7 11651.5 11805.4 11833.7 8145.5 8245.2 8280.2 8413.2 8513.2 9652.7 11003.7 11615.9 11644.5 11873.9 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5703.69 Median:5063.15 stddev:3188.89 (63.0%) stddev-sans-first:3181.30 09:48:03 INFO - PROCESS | 1654 | Values: 1383.4 1540.0 1563.6 1803.5 1987.3 7825.8 8551.1 8812.3 8822.8 8943.2 8593.7 8657.7 8733.9 8744.7 8752.1 2070.3 2160.0 2343.3 2378.3 2379.4 2178.8 2201.9 2204.9 2310.7 2399.8 2357.8 2364.2 2369.2 2371.7 2374.5 2318.5 2326.8 2354.1 2354.5 2356.8 1936.9 1952.9 2265.1 2343.0 2348.2 8705.2 8774.5 8797.0 8803.6 8812.3 5185.4 5355.2 5363.2 5369.7 5370.3 3061.1 3485.5 3592.7 3662.3 3663.2 1076.7 1087.7 1099.1 1119.2 1119.5 11638.7 11653.9 11659.9 11677.4 11680.6 4612.8 4628.6 4785.2 4789.8 4790.9 9883.3 9893.7 9912.1 9919.0 9932.2 5340.9 5356.1 5363.5 5368.0 5368.2 4724.6 4836.1 4904.3 4911.0 4955.6 3549.1 3707.7 3776.5 3860.2 3920.4 1163.1 1163.4 1165.8 1177.1 1178.9 920.4 927.9 933.9 938.3 976.2 4966.7 5905.7 5979.3 5987.0 6142.8 965.4 969.4 973.5 987.3 1006.8 3895.5 4016.9 4071.7 4083.3 4085.4 8994.8 10069.8 10765.0 10774.4 10784.6 10062.6 10618.5 10715.8 10741.5 10743.0 7845.1 8210.0 8229.0 8234.0 8246.8 5997.1 6045.3 6051.1 6166.7 6339.0 9409.8 9657.6 10088.8 10096.7 10101.4 9765.2 10177.8 10216.0 10231.2 10260.8 8086.3 8167.3 8280.3 8299.5 8318.8 7044.5 7570.1 7884.6 8030.8 8333.7 7432.8 7510.5 7546.8 7547.2 7555.0 7431.8 7461.9 7511.3 7521.5 7533.5 6407.8 6838.8 6971.8 7105.2 7140.1 5011.0 5053.6 5115.2 5127.6 5197.4 4140.0 5072.6 5169.8 5178.3 5206.5 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4440.68 Median:4865.11 stddev:1376.91 (28.3%) stddev-sans-first:1345.37 09:48:03 INFO - PROCESS | 1654 | Values: 307.1 310.8 313.8 317.1 320.1 4513.3 5205.7 5281.7 5296.5 5303.0 4367.3 4882.0 5189.1 5231.9 5254.3 3050.7 3419.5 3886.5 4052.5 4061.6 3538.8 3975.8 4085.0 4102.8 4113.4 3426.8 4041.3 4043.3 4055.0 4085.4 3491.2 3832.2 4003.3 4061.8 4102.6 3110.4 3155.9 3532.8 3541.2 3561.2 4863.5 5253.1 5269.5 5273.7 5278.6 4498.8 4671.1 4677.2 4677.4 4712.3 4817.5 5607.2 5624.0 5634.5 5703.1 3342.6 3880.9 3881.6 3927.9 3934.5 5631.2 5881.4 5937.9 5962.2 5976.6 3273.3 3890.6 3901.5 3906.0 3913.6 3917.0 3960.8 3974.7 4003.8 4018.6 3818.4 4007.5 4606.7 4641.0 4652.0 5522.5 5581.8 5593.3 5599.6 5640.7 1337.5 1345.3 1364.4 1368.8 1372.9 834.8 849.3 851.8 866.7 892.9 4632.8 5032.1 5145.8 5216.3 5327.5 5104.5 5193.7 5702.0 5744.8 5758.8 4438.8 4856.4 4900.3 4938.0 5260.3 3019.6 3042.9 3059.8 3071.5 3080.4 5491.6 5595.4 5966.9 5996.3 6092.6 4763.8 5774.0 5894.6 5908.8 5908.8 5283.1 5882.0 5886.4 5951.0 6008.8 5147.6 5466.0 5717.5 5810.2 5863.4 4905.5 4921.5 5337.7 5481.8 5656.5 4866.7 5835.4 5940.3 5954.4 5997.1 2928.1 3204.8 3331.1 3399.9 3407.6 4546.3 4641.7 4935.7 4966.9 4978.8 4348.0 4356.8 4415.9 4817.3 4860.7 4129.0 4271.8 4581.6 4702.0 5143.0 4792.0 4955.8 5473.9 5542.6 5613.9 5318.4 5377.5 5521.9 5555.3 5627.4 4577.9 5531.4 5532.7 5575.9 5675.9 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24277.12 Median:4561.30 stddev:39555.26 (867.2%) stddev-sans-first:39626.56 09:48:03 INFO - PROCESS | 1654 | Values: 685.6 707.4 768.7 811.1 838.8 14963.3 15666.5 15684.9 15693.1 15699.8 12602.9 12619.2 12622.4 13021.7 14556.9 712.6 901.5 917.8 918.2 919.7 731.7 891.1 923.7 924.0 924.6 968.1 971.3 972.3 973.4 975.1 686.7 725.0 963.7 964.9 968.6 696.9 710.6 710.8 717.8 718.4 15310.1 15443.8 15742.7 15758.0 15768.8 13973.0 14075.9 14114.6 14261.6 14332.1 12758.2 12785.1 12808.2 12981.9 13323.5 4615.3 4649.7 4668.2 4777.7 4835.8 84122.6 109692.0 111460.0 111673.0 112728.0 10549.2 10567.0 11346.7 11430.4 11990.6 19264.4 22910.4 23251.2 23388.9 23645.5 8038.7 8068.0 8123.2 8219.8 8355.1 4373.8 4402.1 4470.9 4481.2 4507.3 3512.8 3633.5 3634.2 3636.3 3640.4 599.8 640.6 663.6 670.2 671.5 628.7 640.3 640.7 653.5 673.8 7399.6 8707.0 8743.4 8774.4 8915.6 577.2 588.2 638.7 645.8 646.8 4348.8 4353.7 4388.3 4397.7 4471.9 150359.0 153334.0 155580.0 157184.0 160868.0 83799.0 132003.0 134207.0 134632.0 137500.0 84631.0 89998.6 107664.5 108231.9 109510.0 7968.4 8216.6 8432.0 8486.0 8628.0 62226.4 63524.7 64116.4 64318.7 66280.7 82710.6 90585.5 96570.5 97883.0 99918.5 11557.9 11738.6 11777.7 11778.1 11884.6 12038.5 12076.6 12166.9 12733.2 12767.3 11592.5 11736.0 11830.8 11940.8 12062.9 11324.9 11469.7 11770.0 11833.9 12180.8 11567.8 11570.8 12108.8 12114.5 12133.2 8593.3 8629.7 8675.7 8712.6 8754.0 6403.6 8484.2 8537.7 8660.9 8692.7 09:48:03 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:48:03 INFO - PROCESS | 1654 | 09:48:03 INFO - TEST-INFO | 1654: exit 0 09:48:03 INFO - TEST-OK | dromaeo_css | took 1116526ms 09:48:03 INFO - TEST-START | kraken 09:48:03 INFO - operating with platform_type : mac_ 09:48:03 INFO - Initialising browser for kraken test... 09:48:03 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpQYkYnx/profile http://localhost:49266/getInfo.html) 09:48:06 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 09:48:06 INFO - PROCESS | 1665 | colorDepth:24 09:48:06 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 09:48:06 INFO - PROCESS | 1665 | __metrics 09:48:06 INFO - PROCESS | 1665 | 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" 09:48:06 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:07 INFO - TEST-INFO | 1665: exit 0 09:48:07 INFO - Browser initialized. 09:48:07 INFO - Running cycle 1/1 for kraken test... 09:48:07 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpQYkYnx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:18 INFO - PROCESS | 1666 | RSS: Main: 243134464 09:48:18 INFO - PROCESS | 1666 | 09:48:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 09:48:22 INFO - PROCESS | 1666 | RSS: Main: 293167104 09:48:22 INFO - PROCESS | 1666 | 09:48:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 09:48:25 INFO - PROCESS | 1666 | RSS: Main: 304181248 09:48:25 INFO - PROCESS | 1666 | 09:48:29 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 09:48:29 INFO - PROCESS | 1666 | RSS: Main: 390414336 09:48:29 INFO - PROCESS | 1666 | 09:48:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 09:48:32 INFO - PROCESS | 1666 | RSS: Main: 289398784 09:48:32 INFO - PROCESS | 1666 | 09:48:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:48:35 INFO - PROCESS | 1666 | RSS: Main: 293371904 09:48:35 INFO - PROCESS | 1666 | 09:48:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 09:48:38 INFO - PROCESS | 1666 | RSS: Main: 329809920 09:48:38 INFO - PROCESS | 1666 | 09:48:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 09:48:42 INFO - PROCESS | 1666 | RSS: Main: 331292672 09:48:42 INFO - PROCESS | 1666 | 09:48:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 09:48:45 INFO - PROCESS | 1666 | RSS: Main: 331493376 09:48:45 INFO - PROCESS | 1666 | 09:48:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:48:48 INFO - PROCESS | 1666 | RSS: Main: 310771712 09:48:48 INFO - PROCESS | 1666 | 09:48:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:48:50 INFO - PROCESS | 1666 | RSS: Main: 318164992 09:48:50 INFO - PROCESS | 1666 | 09:48:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:48:53 INFO - PROCESS | 1666 | RSS: Main: 320700416 09:48:53 INFO - PROCESS | 1666 | 09:48:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:48:57 INFO - PROCESS | 1666 | RSS: Main: 324419584 09:48:57 INFO - PROCESS | 1666 | 09:48:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:00 INFO - PROCESS | 1666 | RSS: Main: 325738496 09:49:00 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 09:49:02 INFO - PROCESS | 1666 | RSS: Main: 369692672 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | __start_tp_report 09:49:02 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 09:49:02 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 09:49:02 INFO - PROCESS | 1666 | |i|pagename|runs| 09:49:02 INFO - PROCESS | 1666 | |0;ai-astar;101;112;113;113;113;112;114;113;113;114 09:49:02 INFO - PROCESS | 1666 | |1;audio-beat-detection;156;125;117;168;139;147;143;136;156;118 09:49:02 INFO - PROCESS | 1666 | |2;audio-dft;183;186;186;183;185;181;185;186;185;185 09:49:02 INFO - PROCESS | 1666 | |3;audio-fft;78;74;58;104;56;57;60;62;99;58 09:49:02 INFO - PROCESS | 1666 | |4;audio-oscillator;83;94;129;105;100;70;96;71;68;103 09:49:02 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;91;130;120;84;87;86;89;87;89;131 09:49:02 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;93;94;94;94;94;106;151;93;96 09:49:02 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;88;84;86;80;85;87;85;83;84 09:49:02 INFO - PROCESS | 1666 | |8;json-parse-financial;56;56;55;57;54;55;54;55;56;54 09:49:02 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;68;72;77;72;71;68;68;74;69 09:49:02 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;86;84;84;83;85;102;85;102;86 09:49:02 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;135;137;140;168;168;131;124;143;154;150 09:49:02 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;166;169;168;167;168;178;169;166;165;167 09:49:02 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;66;67;67;67;66;69;66;66;67 09:49:02 INFO - PROCESS | 1666 | __end_tp_report 09:49:02 INFO - PROCESS | 1666 | __start_cc_report 09:49:02 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2040 09:49:02 INFO - PROCESS | 1666 | __end_cc_report 09:49:02 INFO - PROCESS | 1666 | __startTimestamp1467132542441__endTimestamp 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | ------- Summary: start ------- 09:49:02 INFO - PROCESS | 1666 | Number of tests: 14 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.85 (3.4%) stddev-sans-first:0.71 09:49:02 INFO - PROCESS | 1666 | Values: 101.0 112.0 113.0 113.0 113.0 112.0 114.0 113.0 113.0 114.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:145.00 stddev:17.02 (11.7%) stddev-sans-first:17.10 09:49:02 INFO - PROCESS | 1666 | Values: 156.0 125.0 117.0 168.0 139.0 147.0 143.0 136.0 156.0 118.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.65 (0.9%) stddev-sans-first:1.66 09:49:02 INFO - PROCESS | 1666 | Values: 183.0 186.0 186.0 183.0 185.0 181.0 185.0 186.0 185.0 185.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:70.60 Median:61.00 stddev:17.92 (29.4%) stddev-sans-first:18.81 09:49:02 INFO - PROCESS | 1666 | Values: 78.0 74.0 58.0 104.0 56.0 57.0 60.0 62.0 99.0 58.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:70.50 stddev:19.22 (27.3%) stddev-sans-first:20.11 09:49:02 INFO - PROCESS | 1666 | Values: 83.0 94.0 129.0 105.0 100.0 70.0 96.0 71.0 68.0 103.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:87.00 stddev:19.35 (22.2%) stddev-sans-first:20.29 09:49:02 INFO - PROCESS | 1666 | Values: 91.0 130.0 120.0 84.0 87.0 86.0 89.0 87.0 89.0 131.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:94.00 stddev:17.92 (19.1%) stddev-sans-first:18.94 09:49:02 INFO - PROCESS | 1666 | Values: 97.0 93.0 94.0 94.0 94.0 94.0 106.0 151.0 93.0 96.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:2.22 (2.6%) stddev-sans-first:2.35 09:49:02 INFO - PROCESS | 1666 | Values: 84.0 88.0 84.0 86.0 80.0 85.0 87.0 85.0 83.0 84.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.03 (1.9%) stddev-sans-first:1.05 09:49:02 INFO - PROCESS | 1666 | Values: 56.0 56.0 55.0 57.0 54.0 55.0 54.0 55.0 56.0 54.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:71.50 stddev:3.34 (4.7%) stddev-sans-first:3.12 09:49:02 INFO - PROCESS | 1666 | Values: 66.0 68.0 72.0 77.0 72.0 71.0 68.0 68.0 74.0 69.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:85.00 stddev:7.33 (8.6%) stddev-sans-first:7.68 09:49:02 INFO - PROCESS | 1666 | Values: 85.0 86.0 84.0 84.0 83.0 85.0 102.0 85.0 102.0 86.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:146.50 stddev:14.88 (10.2%) stddev-sans-first:15.34 09:49:02 INFO - PROCESS | 1666 | Values: 135.0 137.0 140.0 168.0 168.0 131.0 124.0 143.0 154.0 150.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:168.00 stddev:3.65 (2.2%) stddev-sans-first:3.78 09:49:02 INFO - PROCESS | 1666 | Values: 166.0 169.0 168.0 167.0 168.0 178.0 169.0 166.0 165.0 167.0 09:49:02 INFO - PROCESS | 1666 | 09:49:02 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 09:49:02 INFO - PROCESS | 1666 | Values: 67.0 66.0 67.0 67.0 67.0 66.0 69.0 66.0 66.0 67.0 09:49:02 INFO - PROCESS | 1666 | -------- Summary: end -------- 09:49:02 INFO - PROCESS | 1666 | 09:49:03 INFO - TEST-INFO | 1666: exit 0 09:49:03 INFO - TEST-OK | kraken | took 59374ms 09:49:03 INFO - SUITE-END | took 1175s 09:49:03 INFO - Completed test suite (00:19:36) 09:49:03 INFO - javascript benchmark 09:49:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3371.8145501997396, 3380.79715217087, 3406.2506248625295, 3419.076849250703, 3439.6216416194216, 5055.710793747594, 5636.802711905084, 5648.830535083949, 5725.770969161229, 5773.336066352371, 5800.738966746496, 5803.883922321562, 5945.70271486425, 5955.0, 5983.9102413463725, 5176.731700733307, 5196.402413722413, 5271.578273738091, 5277.577793776489, 5337.626366154371, 5329.920051199216, 5390.0, 5402.9729851350885, 5412.296401467804, 5436.673799572019, 5166.225066240076, 5215.24378965048, 5224.92162617561, 5284.947150528484, 5293.708846013467, 5204.9219261711105, 5224.242484839677, 5227.555657769094, 5255.76349064293, 5258.684478931276, 5199.402068762103, 5253.816116435906, 5284.5243928046275, 5323.653962492425, 5345.091334473149, 5643.6049476536655, 5705.515031222351, 5868.794592189253, 5939.613925094855, 5952.851178720567, 5622.8313150605545, 6046.365131661156, 6125.938740612582, 6133.325334213233, 6140.13616995768, 6193.380661933816, 6245.656488893109, 6255.24937007562, 6255.812325630239, 6268.09112678659, 5817.825465236049, 6051.425114614105, 6067.120267561223, 6072.967595508774, 6079.817605471843, 6210.9068363974575, 6239.251289845248, 6300.369963003663, 6324.146240257506, 6401.391867772554, 6103.057498482739, 6104.633721976696, 6115.388461153849, 6130.474976888307, 6130.478909292671, 6115.694215289218, 6136.754529818757, 6186.133941248228, 6264.498840092781, 6266.530010249158, 5848.298204215522, 5887.882242355215, 6106.430331452215, 6116.092308610896, 6166.630002199882, 6121.84724866694, 6150.04674275488, 6163.96918015407, 6243.25080990275, 6250.343713910065, 4973.47778483254, 4983.451820299764, 4983.551480366785, 4986.900261994812, 5014.197728363444, 4400.471943366816, 4404.955950440519, 4436.068425630589, 4509.549045095464, 4511.593956543927, 5766.0, 5804.4775970162045, 5821.563382746226, 5876.294844618673, 5971.164037034819, 6232.345603711636, 6245.500359971192, 6254.15568898202, 6263.0, 6278.466330361879, 5824.097264923944, 5879.823605291848, 5909.3204281507315, 5914.319853216849, 5930.644161350333, 5345.33183352081, 5401.972990135024, 5421.484958928974, 5480.04099282624, 5481.232627432163, 6335.524835637346, 6335.778247761306, 6364.554481186365, 6366.522510811615, 6368.840778980562, 6196.907046394398, 6211.565190436807, 6223.751049957951, 6239.78160764371, 6257.937420625735, 6200.720967556561, 6228.03465462854, 6239.844003899939, 6286.8428289291305, 6316.368363163647, 6240.157578726814, 6243.500519958483, 6263.123162757127, 6264.498840092872, 6268.780592679234, 4876.463589005278, 6107.931112055461, 6231.563790534619, 6244.5316601256, 6279.277883043325, 5091.974540127276, 6078.878422431615, 6132.569672417391, 6145.647957449354, 6229.937700622936, 6152.815415537541, 6179.660118693515, 6184.319724830355, 6185.381461853779, 6234.719437625408, 5821.155932389695, 6058.818235452944, 6082.321852465646, 6104.542159338156, 6108.358622344767, 6128.662923539248, 6179.196704428396, 6184.5052395809125, 6241.656708881055, 6253.312135664983, 6070.362611925684, 6083.452489275986, 6091.994820854687, 6108.541859360477, 6122.815596962102], "value": 5734.655651989078, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.49440828742235, 491.83523519620906, 513.9797501958612, 521.9660722053064, 529.5604648142042, 31681.841590792043, 42715.57284427155, 42852.57147428525, 42898.99999999996, 44660.0, 43178.56821431785, 43505.12989740203, 43522.347164792554, 43701.78149109258, 43731.0, 8434.789130271762, 8711.564421778918, 8939.418937769024, 9623.951880240624, 9789.853152202722, 11794.646160615195, 12030.0, 12044.976177024964, 12152.39238038099, 12172.93913530431, 11921.0, 11996.280223186637, 12011.519539218421, 12023.218490798114, 12034.63896083119, 8813.07462716412, 11809.586664466702, 11904.285742855413, 12045.397730113547, 12062.457189426497, 11592.826107608393, 11650.941745291218, 11653.766924661544, 11665.183437159401, 11667.183297169202, 34360.48459273113, 41294.0, 41635.583644163475, 41665.37501937473, 41739.0, 3145.874165033396, 3305.7107727986104, 3407.6251612322626, 3418.025862629139, 3426.4860270959307, 31831.0, 32069.877554285722, 32586.837065814758, 32792.836035819666, 33255.667443325496, 3244.8864289749745, 3311.718503927169, 3320.335932813447, 3385.0352649494776, 3393.100828280508, 454988.0, 515802.0, 520584.0, 524875.0, 525203.0, 34314.82842585771, 56792.14811777827, 57006.0, 58045.41954580485, 65150.67424662846, 34823.65176348255, 35897.820510897276, 35920.640793592254, 35956.0, 35965.82017089898, 14981.40074397012, 15098.773518397233, 15170.165640889856, 15171.620709482351, 15195.696086078438, 10434.68695939123, 11065.114790816715, 11421.600243991421, 11624.0, 11861.525538978343, 7406.444516661272, 7629.771106866692, 7825.491343062797, 7833.09919359281, 7851.254130857559, 3315.950260746091, 3327.9168020799675, 3330.1508115430893, 3340.6993370596765, 3376.594808622981, 928.1831987850783, 984.7291994701371, 990.5195979949863, 990.6928852055886, 1082.8105081610686, 30299.0, 30833.845830770704, 30856.53715194274, 31103.84448077745, 31333.06000819979, 1085.46812062089, 1104.7514309280348, 1148.529103067755, 1170.3387586014046, 1173.4074292482242, 15756.212189390484, 15988.520344389686, 15989.040657560583, 16062.839371606367, 16118.435854745028, 692408.0, 700875.0, 702107.4894625494, 703432.0, 704096.0, 47295.763521182176, 60211.0, 60424.697876510334, 60438.0, 60446.0, 1162.3084264862393, 1193.1111322064944, 1338.491373278148, 1407.978880316796, 1421.133108803629, 29690.554641680825, 29967.384667318434, 31701.5244771324, 31803.68196318007, 32154.356912861145, 50733.23900141575, 55895.72052139713, 56197.87604247974, 56633.43366566447, 59656.80686386335, 636140.0, 641879.0, 648207.0, 648709.0, 651041.4895850981, 36018.09954751152, 36184.81907590445, 43338.13323733445, 43821.904452388946, 44620.0, 45189.54810451854, 47950.0, 48439.75780121218, 49457.752711237656, 50604.0, 40009.399859001554, 40217.1956560873, 40452.25503268613, 41378.793106034274, 41511.58488415198, 38412.23175536418, 39086.804565976985, 39463.80268098756, 39930.80034599808, 40055.1988960225, 38073.80963095167, 38247.0, 38763.0, 38867.6113238864, 38916.027099322746, 32303.192420189684, 32601.34797303993, 34331.65668343285, 34392.0, 34907.65092349044, 25579.360515986504, 25630.0, 25817.354566135997, 26001.219963401127, 26324.736752632994], "value": 19626.282270264004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.8457574682253, 384.5788861196989, 390.18840811112887, 402.73419543101556, 412.6801728660288, 19718.605627887435, 20108.4972875678, 21451.892740536296, 21471.355859324205, 22487.0, 20025.0, 21068.367948961517, 21147.894260528716, 21344.57310853782, 21819.01814418351, 5202.751339678469, 5867.970660146693, 6016.548758843082, 6024.2770867495965, 6032.638041717511, 3849.0, 4117.0, 4697.67116301859, 5829.912551311733, 6097.237845269341, 6120.755169793203, 6127.877442451148, 6139.815805525842, 6160.5687601867885, 6165.136880836686, 5563.0, 5850.444207800273, 6126.0, 6132.816015519542, 6152.107944348089, 3780.451834484012, 4013.8394464221687, 4055.6755459563165, 5888.911666325009, 5912.4974377178, 25323.493530129294, 26069.478610427876, 26113.0, 26516.86741566299, 26525.867370663214, 1938.932137375199, 2114.217739436403, 2145.324222869791, 2166.9133234670594, 2917.8182835951475, 9659.372140810896, 9978.70063898077, 10641.680749577525, 10644.361338319726, 10677.30597511167, 1550.8139023317162, 1946.2896042944365, 1993.5414854583382, 2038.7451568553931, 2050.261905713942, 113440.89515971801, 122881.0, 125014.37492812569, 125359.0, 126216.36891815574, 11225.214235003472, 11645.243059201124, 11662.88337116635, 11698.70753231176, 11888.583899563473, 14855.034422762703, 14964.551063468114, 14979.951403401657, 14980.925095374454, 15016.549503514912, 6925.926481395391, 8586.785330366665, 8633.827323453621, 8666.306695464475, 8715.084916083846, 6998.0, 8520.488770673779, 8528.573571321409, 8623.0, 8663.090375510606, 4523.072770082125, 4615.376924115269, 4623.676342655982, 4656.8602941911795, 4692.929606055912, 1563.3277690592927, 1813.165943665902, 1823.7925175964551, 1898.620275944789, 1973.6940774180025, 943.0, 980.4705459052032, 984.8818141823027, 999.9150072243796, 1009.3489699144158, 22106.894655267173, 22361.329160125224, 22696.319110426713, 22696.886515567316, 22786.08855645789, 939.2157548446986, 942.330945029037, 949.2738055387634, 971.6842026341467, 972.4214092614883, 11408.087353011739, 11741.647750567497, 11802.232854864578, 11822.290662560274, 11874.881251187551, 550289.0, 561225.5203411847, 562007.0, 568923.3984470709, 586288.0, 124884.0, 130872.0, 132901.0, 133384.9992250117, 136101.3194934019, 957.200737384278, 966.21253678252, 978.5205249427871, 979.4172467381896, 1014.3508154781093, 19346.806531935064, 19601.70597441068, 19677.901610491856, 19724.112414941075, 19795.406137815313, 50409.99180016453, 52417.95164096773, 53505.7324713374, 53708.731456342466, 54449.455505444435, 70603.64698176476, 82315.0, 84451.0, 85466.0, 93578.53210733902, 5441.5102640762525, 6266.968665156827, 6822.897656535057, 7324.828027515572, 8244.29923456513, 11734.647960561197, 12858.871411285767, 12943.935280323914, 13210.405531751287, 13266.00504962151, 10706.839397408889, 11346.489407976826, 11519.827202592136, 11597.13021523406, 11600.24598401135, 10988.230823842574, 11320.867913208613, 11371.772564548828, 11515.53937842477, 11572.305661660328, 11501.482433290355, 11618.70953226176, 11651.533938642699, 11805.409729513662, 11833.704157396134, 8145.470544414831, 8245.216704413071, 8280.171982801672, 8413.200745929127, 8513.19124683154, 9652.710418687451, 11003.671543792232, 11615.88384116148, 11644.475998579916, 11873.940630296793], "value": 9610.093739929502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.4120498788013, 1539.9538013859585, 1563.5543869997052, 1803.4679769468005, 1987.2547794577033, 7825.804354891131, 8551.059383467822, 8812.250958668514, 8822.823543529126, 8943.150400711938, 8593.656253749858, 8657.653693852255, 8733.91266087339, 8744.737657870273, 8752.124787521245, 2070.2754036087326, 2160.0, 2343.3087239264373, 2378.2746262389965, 2379.4408314046186, 2178.8474806763534, 2201.9009144588454, 2204.8787316697576, 2310.7111611048617, 2399.8200134989856, 2357.7760112789256, 2364.2316247219637, 2369.218158007862, 2371.6561098640686, 2374.465745207332, 2318.455163036685, 2326.848754830939, 2354.0701422938014, 2354.4937838364835, 2356.787889089973, 1936.8741031832885, 1952.8535359848067, 2265.1392470861133, 2342.953140937189, 2348.1781376518084, 8705.216530512283, 8774.473531588124, 8797.032326444087, 8803.603837827313, 8812.295016398673, 5185.429602743695, 5355.196720491918, 5363.168708850135, 5369.677819330853, 5370.275012873251, 3061.11227743954, 3485.4597537381746, 3592.7125829933543, 3662.285854258394, 3663.230721548478, 1076.6662334676275, 1087.6682611803396, 1099.120703437247, 1119.2333251722596, 1119.5018216893486, 11638.709032274177, 11653.941730291379, 11659.883401165964, 11677.357745324003, 11680.649580512598, 4612.815487380534, 4628.583427491474, 4785.1625965455905, 4789.784459699322, 4790.880227994328, 9883.258755593359, 9893.65372211969, 9912.058354456316, 9918.950405247928, 9932.155766759763, 5340.893182136336, 5356.143017117242, 5363.463653634683, 5368.0, 5368.194770784414, 4724.574788269004, 4836.081144582518, 4904.337914381585, 4910.950890491121, 4955.578775804025, 3549.1304630365726, 3707.740458167956, 3776.4712940188397, 3860.1700634363747, 3920.353141731582, 1163.1450883600546, 1163.4241050679968, 1165.8018136916744, 1177.14068729826, 1178.8526434195726, 920.397139873384, 927.9164875161268, 933.8879334479906, 938.2681508423441, 976.1800087926176, 4966.701997880139, 5905.675187864708, 5979.252593425822, 5986.970065149646, 6142.806098141948, 965.3628605120728, 969.4427682537175, 973.4889183178888, 987.3286165407449, 1006.8036732837027, 3895.53253609563, 4016.8995775105855, 4071.6742660587083, 4083.3466645336603, 4085.428040074392, 8994.820103597891, 10069.79860402788, 10765.0, 10774.407407592658, 10784.56861725538, 10062.597496100074, 10618.469076546142, 10715.785684286271, 10741.462926853783, 10743.033127018605, 7845.137034926153, 8210.014798224132, 8229.012518497699, 8234.0, 8246.8350632987, 5997.100434934795, 6045.274567051982, 6051.12258722474, 6166.722497487534, 6339.0, 9409.811803763749, 9657.613695452103, 10088.798224035625, 10096.69709908704, 10101.44442055694, 9765.21878249724, 10177.796444071226, 10216.029477199654, 10231.181505479395, 10260.846087308846, 8086.272235498834, 8167.346612270885, 8280.254777070093, 8299.502029878227, 8318.83362332762, 7044.542104763174, 7570.05374328209, 7884.566348850868, 8030.759077227693, 8333.749987500385, 7432.777016689508, 7510.47426680149, 7546.849063018819, 7547.207543208104, 7554.962225188839, 7431.8142046449275, 7461.925380746123, 7511.32398084175, 7521.548707077593, 7533.472656913963, 6407.807765767035, 6838.760643377458, 6971.825704357431, 7105.218425973242, 7140.07179066718, 5010.974945125251, 5053.646244762831, 5115.18157094863, 5127.6410651254055, 5197.376314842092, 4139.97930010348, 5072.644914856072, 5169.844904652867, 5178.275041494269, 5206.5314121729225], "value": 4582.372537024534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.0649871142371, 310.7917695144254, 313.79760054764677, 317.14687490650164, 320.1260558674819, 4513.277875539914, 5205.739713014344, 5281.656692314997, 5296.523312901838, 5303.0, 4367.257566213743, 4882.0, 5189.091908915943, 5231.895362092756, 5254.344881362368, 3050.7254347108756, 3419.4870769384543, 3886.4753258310047, 4052.5136983562015, 4061.5735347788354, 3538.82305884706, 3975.7813320267373, 4085.0400155963325, 4102.79486025699, 4113.3957756475, 3426.828658567074, 4041.292773764594, 4043.3126368517424, 4055.0065234017707, 4085.407615895693, 3491.202030954048, 3832.2143960488033, 4003.319435695939, 4061.776602286873, 4102.5692302308125, 3110.4401207782817, 3155.857986390618, 3532.7703699259464, 3541.1501239702543, 3561.2343346180437, 4863.53796389346, 5253.058287359053, 5269.5484457679895, 5273.679580984354, 5278.604104692164, 4498.8425405110665, 4671.089137618166, 4677.204875171229, 4677.438707355105, 4712.293156026589, 4817.51824817519, 5607.158926161109, 5624.0, 5634.549236061146, 5703.144528320744, 3342.632310445849, 3880.902977425587, 3881.573026967031, 3927.9018024549327, 3934.5081864766903, 5631.18347839561, 5881.411858814127, 5937.910931336033, 5962.224910761616, 5976.581639285252, 3273.3289675616443, 3890.5914878937306, 3901.49280593521, 3906.0, 3913.5890731472787, 3916.9815847879186, 3960.7623542587535, 3974.6621537169085, 4003.839846406111, 4018.618231268025, 3818.3653279237237, 4007.5391329997424, 4606.742696078897, 4640.9535904640525, 4652.023075154188, 5522.459668552511, 5581.795190899653, 5593.300837395325, 5599.552035837123, 5640.717964101778, 1337.4783834304628, 1345.2533843716635, 1364.379207460603, 1368.8220531330862, 1372.8673844078676, 834.845553572591, 849.3205435651454, 851.7742798158492, 866.6706651472526, 892.8705337725993, 4632.768361581975, 5032.094223039815, 5145.842185508231, 5216.321878155815, 5327.547158491494, 5104.498795590208, 5193.675612718733, 5701.971490142523, 5744.798932037359, 5758.798442054508, 4438.800253988578, 4856.368672072608, 4900.275790399322, 4937.950620493821, 5260.342457192851, 3019.5923550320426, 3042.9239269018453, 3059.831709255968, 3071.4778487656704, 3080.3531258435532, 5491.588130890119, 5595.38450770415, 5966.866295403859, 5996.342231238941, 6092.573519853568, 4763.809447622126, 5774.047282198474, 5894.587378883437, 5908.763649454059, 5908.822735317947, 5283.094298136087, 5882.0, 5886.381929897299, 5950.9702451487465, 6008.8197354078575, 5147.613928955268, 5466.0, 5717.485426311652, 5810.244668193108, 5863.384344643751, 4905.460399355997, 4921.532454416824, 5337.706426146444, 5481.780728770805, 5656.547476201977, 4866.683665561727, 5835.44563266506, 5940.28716553999, 5954.409831723215, 5997.13041608973, 2928.1215635308895, 3204.8077115373153, 3331.06730115568, 3399.9320013600086, 3407.625161232213, 4546.340196142138, 4641.6518761092375, 4935.716713654548, 4966.925496117633, 4978.775955081957, 4348.043430445297, 4356.847510337123, 4415.9116817662825, 4817.349657796293, 4860.659753817199, 4128.967758060485, 4271.807768650356, 4581.5647513486165, 4701.9529804701515, 5143.0, 4792.017636384602, 4955.8017679292425, 5473.907242408027, 5542.639728417801, 5613.859653508695, 5318.4149743527405, 5377.516023557899, 5521.889562208653, 5555.30558680165, 5627.352854421794, 4577.862664120082, 5531.446855314436, 5532.723363831873, 5575.944240557543, 5675.858103547444], "value": 4010.2340517688717, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.5715178013742, 707.4022451028878, 768.7078910014194, 811.1482942909943, 838.8112674648205, 14963.251837408126, 15666.530004099866, 15684.921575392122, 15693.136877471734, 15699.764503532442, 12602.873971260307, 12619.24284542928, 12622.431990560424, 13021.73956520869, 14556.927215363934, 712.5760172697263, 901.5176880369004, 917.7567944494714, 918.2332752151927, 919.7286800393866, 731.6926890705889, 891.1445012787699, 923.6859467780953, 924.0113079005467, 924.5654542365111, 968.099667309402, 971.2715463402449, 972.3436680240809, 973.4110862927932, 975.1126474907835, 686.6507313129724, 724.9637518124125, 963.6964356227766, 964.8793900762405, 968.6367612145445, 696.9163700355939, 710.6127160697432, 710.8187412209905, 717.7954283029363, 718.392957950531, 15310.081395116218, 15443.768343474856, 15742.685146297124, 15758.0, 15768.842311576967, 13973.0, 14075.929620351935, 14114.647133821634, 14261.643458913506, 14332.06841555306, 12758.234505929673, 12785.10504264702, 12808.23150610957, 12981.870181298254, 13323.467061317535, 4615.330777037344, 4649.721016738973, 4668.206404911173, 4777.713337199779, 4835.806567737286, 84122.57938710267, 109692.0, 111460.0, 111673.0, 112727.99999999919, 10549.20880933933, 10566.996135367128, 11346.716332091599, 11430.371329576787, 11990.580329688419, 19264.422067338, 22910.42723931882, 23251.18620848262, 23388.883055584614, 23645.527089458457, 8038.678452861937, 8068.0, 8123.228293312126, 8219.8356032879, 8355.122712115262, 4373.75944323059, 4402.097569998143, 4470.865874023784, 4481.238189507726, 4507.2788353863225, 3512.8067956262653, 3633.454981752758, 3634.200475895299, 3636.2727454508677, 3640.3629364861044, 599.8033922324989, 640.5836206465741, 663.6416335179046, 670.1924181361447, 671.5467059734659, 628.6510986402591, 640.2828831708516, 640.6674117834986, 653.5294587896707, 673.7877568565887, 7399.59302238382, 8706.956465217634, 8743.431676940978, 8774.385792994557, 8915.552553686019, 577.206341280739, 588.1824264272665, 638.7349250061266, 645.7675236914707, 646.7815393201271, 4348.804303806273, 4353.69524133314, 4388.29787234041, 4397.670174736908, 4471.888202794956, 150359.0, 153334.0, 155580.0, 157184.0, 160868.0, 83799.0, 132003.01995470075, 134207.0, 134632.0, 137500.0, 84631.0, 89998.55000724955, 107664.46167769424, 108231.91768082218, 109510.0, 7968.402369822171, 8216.630251638571, 8431.95784021076, 8486.0, 8627.95686021566, 62226.37773622387, 63524.74178356969, 64116.35883641104, 64318.678406607665, 66280.6685966567, 82710.58644706738, 90585.54707226422, 96570.51714741382, 97883.0, 99918.5004074975, 11557.88442115568, 11738.58914937973, 11777.705557361134, 11778.116641251769, 11884.643460696192, 12038.518459261531, 12076.577319793765, 12166.939165304117, 12733.172343797623, 12767.297798621164, 11592.478338474957, 11736.0, 11830.763384732085, 11940.820887686865, 12062.879371206176, 11324.94337528307, 11469.655910322703, 11770.0, 11833.940830295793, 12180.75638487243, 11567.768644626893, 11570.768584628091, 12108.818367724667, 12114.454849531969, 12133.150679452707, 8593.31253499731, 8629.69796057135, 8675.652973880975, 8712.649266504264, 8753.956230218808, 6403.583767055313, 8484.151584841466, 8537.74386768398, 8660.913390866011, 8692.739217823475], "value": 7609.653640877047, "unit": "score"}], "name": "dromaeo_css", "value": 7299.459484934121, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 114.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 117.0, 168.0, 139.0, 147.0, 143.0, 136.0, 156.0, 118.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 186.0, 183.0, 185.0, 181.0, 185.0, 186.0, 185.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 104.0, 56.0, 57.0, 60.0, 62.0, 99.0, 58.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 94.0, 129.0, 105.0, 100.0, 70.0, 96.0, 71.0, 68.0, 103.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 130.0, 120.0, 84.0, 87.0, 86.0, 89.0, 87.0, 89.0, 131.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 94.0, 94.0, 94.0, 94.0, 106.0, 151.0, 93.0, 96.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 84.0, 86.0, 80.0, 85.0, 87.0, 85.0, 83.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 57.0, 54.0, 55.0, 54.0, 55.0, 56.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 72.0, 77.0, 72.0, 71.0, 68.0, 68.0, 74.0, 69.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 83.0, 85.0, 102.0, 85.0, 102.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 140.0, 168.0, 168.0, 131.0, 124.0, 143.0, 154.0, 150.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 168.0, 167.0, 168.0, 178.0, 169.0, 166.0, 165.0, 167.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 67.0, 66.0, 69.0, 66.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1478.5, "alertThreshold": 2.0}]} 09:49:03 INFO - Return code: 0 09:49:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:49:03 INFO - # TBPL SUCCESS # 09:49:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:03 INFO - 2016-06-28 09:49:03.779 screenresolution[1670:9858] starting screenresolution argv=screenresolution get 09:49:03 INFO - 2016-06-28 09:49:03.782 screenresolution[1670:9858] Display 0: 1600x1200x32@60 09:49:03 INFO - 2016-06-28 09:49:03.787 screenresolution[1671:9861] starting screenresolution argv=screenresolution list 09:49:03 INFO - Available Modes on Display 0 09:49:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:04 INFO - Intel Iris: 09:49:04 INFO - Chipset Model: Intel Iris 09:49:04 INFO - Type: GPU 09:49:04 INFO - Bus: Built-In 09:49:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:04 INFO - Vendor: Intel (0x8086) 09:49:04 INFO - Device ID: 0x0a2e 09:49:04 INFO - Revision ID: 0x0009 09:49:04 INFO - Displays: 09:49:04 INFO - Display: 09:49:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:04 INFO - Display Serial Number: 1600x1200 60 09:49:04 INFO - Main Display: Yes 09:49:04 INFO - Mirror: Off 09:49:04 INFO - Online: Yes 09:49:04 INFO - Rotation: Supported 09:49:04 INFO - Return code: 0 09:49:04 INFO - Running post-action listener: _resource_record_post_action 09:49:04 INFO - ##### 09:49:04 INFO - ##### Finished run-tests step (success) 09:49:04 INFO - ##### 09:49:04 INFO - Running post-run listener: _resource_record_post_run 09:49:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38016512; Write bytes: 706008064; Read time: 390; Write time: 1945 09:49:04 INFO - TinderboxPrint: CPU usage
53.8% 09:49:04 INFO - TinderboxPrint: I/O read bytes / time
38,016,512 / 390 09:49:04 INFO - TinderboxPrint: I/O write bytes / time
706,008,064 / 1,945 09:49:04 INFO - TinderboxPrint: CPU idle
2,239.2 (46.2%) 09:49:04 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 09:49:04 INFO - TinderboxPrint: CPU user
1,641.8 (33.9%) 09:49:04 INFO - TinderboxPrint: Swap in / out
444,731,392 / 0 09:49:04 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 406841856; Write bytes: 401848320; Read time: 31178; Write time: 803 09:49:04 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34514432; Write bytes: 294407168; Read time: 355; Write time: 1115 09:49:04 INFO - Running post-run listener: _upload_blobber_files 09:49:04 INFO - Blob upload gear active. 09:49:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:49:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:49:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:49:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:06 INFO - (blobuploader) - INFO - Done attempting. 09:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:07 INFO - (blobuploader) - INFO - Done attempting. 09:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:49:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:09 INFO - (blobuploader) - INFO - Done attempting. 09:49:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:49:09 INFO - Return code: 0 09:49:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:49:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:49:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69e3fd616fb9a2265fa00997af0e2b47cac4dac23cdcb67e156fad486f70b76347f2a37adeab90eb27e3e910728ea34d992e2761abb1bfb9d689e3389825598c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a251f8cfc3da05984334fd2bada04a9db1bd4533acef5e6d76a3060814906775240d065ea185a26eb6e6028c2575487f3fe187c42b090cbbeb3d52cbdf672b06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211aef5b5bdeb5c2f760b0d1f87610d3c14d17a3175af33939f68566372cf6f3393933767de31c44fa09c02abee6f0592c49a5dfe294b285888b2fafd4f8e5a7"} 09:49:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:49:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:49:09 INFO - Contents: 09:49:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69e3fd616fb9a2265fa00997af0e2b47cac4dac23cdcb67e156fad486f70b76347f2a37adeab90eb27e3e910728ea34d992e2761abb1bfb9d689e3389825598c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a251f8cfc3da05984334fd2bada04a9db1bd4533acef5e6d76a3060814906775240d065ea185a26eb6e6028c2575487f3fe187c42b090cbbeb3d52cbdf672b06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211aef5b5bdeb5c2f760b0d1f87610d3c14d17a3175af33939f68566372cf6f3393933767de31c44fa09c02abee6f0592c49a5dfe294b285888b2fafd4f8e5a7"} 09:49:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:49:09 INFO - Copying logs to upload dir... 09:49:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:49:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.850692 ========= master_lag: 2.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-28 09:49:11.828207) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 09:49:11.835660) ========= 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.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69e3fd616fb9a2265fa00997af0e2b47cac4dac23cdcb67e156fad486f70b76347f2a37adeab90eb27e3e910728ea34d992e2761abb1bfb9d689e3389825598c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a251f8cfc3da05984334fd2bada04a9db1bd4533acef5e6d76a3060814906775240d065ea185a26eb6e6028c2575487f3fe187c42b090cbbeb3d52cbdf672b06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211aef5b5bdeb5c2f760b0d1f87610d3c14d17a3175af33939f68566372cf6f3393933767de31c44fa09c02abee6f0592c49a5dfe294b285888b2fafd4f8e5a7"} build_url:https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/NdlXH8vAT9ijmtrrSzfe9w/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69e3fd616fb9a2265fa00997af0e2b47cac4dac23cdcb67e156fad486f70b76347f2a37adeab90eb27e3e910728ea34d992e2761abb1bfb9d689e3389825598c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a251f8cfc3da05984334fd2bada04a9db1bd4533acef5e6d76a3060814906775240d065ea185a26eb6e6028c2575487f3fe187c42b090cbbeb3d52cbdf672b06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/211aef5b5bdeb5c2f760b0d1f87610d3c14d17a3175af33939f68566372cf6f3393933767de31c44fa09c02abee6f0592c49a5dfe294b285888b2fafd4f8e5a7"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 09:49:12.066502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:49:12.066848) ========= 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.CtV00UKEtE/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.LuUAXMVArA/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005121 ========= master_lag: 0.53 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 09:49:12.603794) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 09:49:12.604178) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 09:49:12.610091) ========= ========= Total master_lag: 5.21 =========