builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1468039860.38 results: success (0) buildid: 20160708201526 builduid: 19e7d467f54e4b00bf042dec0bd092d2 revision: 57d42a4e2ff190b3699fd210891bd5026cc40c22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.384221) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.384664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.384942) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.478972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.479260) ========= 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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-07-08 21:51:00-- 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% 19.4M=0.001s 2016-07-08 21:51:00 (19.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.647150) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.647436) ========= 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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.026580 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:00.695465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 21:51:00.695774) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 57d42a4e2ff190b3699fd210891bd5026cc40c22 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 57d42a4e2ff190b3699fd210891bd5026cc40c22 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-07-08 21:51:00,777 truncating revision to first 12 chars 2016-07-08 21:51:00,777 Setting DEBUG logging. 2016-07-08 21:51:00,777 attempt 1/10 2016-07-08 21:51:00,777 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/57d42a4e2ff1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 21:51:02,163 unpacking tar archive at: fx-team-57d42a4e2ff1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.672539 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 21:51:02.389342) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:02.389615) ========= script_repo_revision: 57d42a4e2ff190b3699fd210891bd5026cc40c22 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:02.390016) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:02.390299) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 21:51:02.414957) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-08 21:51:02.415277) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:02 INFO - MultiFileLogger online at 20160708 21:51:02 in /builds/slave/test 21:51:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:51:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:51:02 INFO - {'append_to_log': False, 21:51:02 INFO - 'base_work_dir': '/builds/slave/test', 21:51:02 INFO - 'blob_upload_branch': 'Fx-Team', 21:51:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:51:02 INFO - 'branch': 'Fx-Team', 21:51:02 INFO - 'buildbot_json_path': 'buildprops.json', 21:51:02 INFO - 'config_files': ('talos/mac_config.py',), 21:51:02 INFO - 'default_actions': ('clobber', 21:51:02 INFO - 'read-buildbot-config', 21:51:02 INFO - 'download-and-extract', 21:51:02 INFO - 'populate-webroot', 21:51:02 INFO - 'create-virtualenv', 21:51:02 INFO - 'install', 21:51:02 INFO - 'run-tests'), 21:51:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:51:02 INFO - 'download_minidump_stackwalk': True, 21:51:02 INFO - 'download_symbols': 'ondemand', 21:51:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:51:02 INFO - 'tooltool.py': '/tools/tooltool.py', 21:51:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:51:02 INFO - '/tools/misc-python/virtualenv.py')}, 21:51:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:51:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:51:02 INFO - 'installer_path': 'installer.exe', 21:51:02 INFO - 'log_level': 'info', 21:51:02 INFO - 'log_name': 'talos', 21:51:02 INFO - 'log_to_console': True, 21:51:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:51:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:51:02 INFO - 'opt_config_files': (), 21:51:02 INFO - 'pip_index': False, 21:51:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:51:02 INFO - 'cmd': ('bash', 21:51:02 INFO - '-c', 21:51:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:51:02 INFO - 'enabled': True, 21:51:02 INFO - 'halt_on_failure': False, 21:51:02 INFO - 'name': 'check_screen_resolution'},), 21:51:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:51:02 INFO - 'cmd': ('bash', 21:51:02 INFO - '-c', 21:51:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:51:02 INFO - 'enabled': True, 21:51:02 INFO - 'halt_on_failure': False, 21:51:02 INFO - 'name': 'check_screen_resolution'},), 21:51:02 INFO - 'run_cmd_checks_enabled': True, 21:51:02 INFO - 'sps_profile': False, 21:51:02 INFO - 'sps_profile_interval': 0, 21:51:02 INFO - 'suite': 'dromaeojs', 21:51:02 INFO - 'system_bits': '32', 21:51:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:51:02 INFO - 'title': 't-yosemite-r7-0296', 21:51:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:51:02 INFO - 'use_talos_json': True, 21:51:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:51:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:51:02 INFO - 'work_dir': 'build'} 21:51:02 INFO - ##### 21:51:02 INFO - ##### Running clobber step. 21:51:02 INFO - ##### 21:51:02 INFO - Running pre-action listener: _resource_record_pre_action 21:51:02 INFO - Running main action method: clobber 21:51:02 INFO - rmtree: /builds/slave/test/build 21:51:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:51:04 INFO - Running post-action listener: _resource_record_post_action 21:51:04 INFO - ##### 21:51:04 INFO - ##### Finished clobber step (success) 21:51:04 INFO - ##### 21:51:04 INFO - ##### 21:51:04 INFO - ##### Running read-buildbot-config step. 21:51:04 INFO - ##### 21:51:04 INFO - Running pre-action listener: _resource_record_pre_action 21:51:04 INFO - Running main action method: read_buildbot_config 21:51:04 INFO - Using buildbot properties: 21:51:04 INFO - { 21:51:04 INFO - "project": "", 21:51:04 INFO - "product": "firefox", 21:51:04 INFO - "script_repo_revision": "production", 21:51:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:51:04 INFO - "repository": "", 21:51:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:51:04 INFO - "buildid": "20160708201526", 21:51:04 INFO - "pgo_build": "False", 21:51:04 INFO - "basedir": "/builds/slave/test", 21:51:04 INFO - "buildnumber": 796, 21:51:04 INFO - "slavename": "t-yosemite-r7-0296", 21:51:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 21:51:04 INFO - "platform": "yosemite_r7", 21:51:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:51:04 INFO - "branch": "fx-team", 21:51:04 INFO - "repo_path": "integration/fx-team", 21:51:04 INFO - "revision": "57d42a4e2ff190b3699fd210891bd5026cc40c22", 21:51:04 INFO - "stage_platform": "macosx64", 21:51:04 INFO - "builduid": "19e7d467f54e4b00bf042dec0bd092d2", 21:51:04 INFO - "slavebuilddir": "test" 21:51:04 INFO - } 21:51:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 21:51:04 INFO - Running post-action listener: _resource_record_post_action 21:51:04 INFO - ##### 21:51:04 INFO - ##### Finished read-buildbot-config step (success) 21:51:04 INFO - ##### 21:51:04 INFO - ##### 21:51:04 INFO - ##### Running download-and-extract step. 21:51:04 INFO - ##### 21:51:04 INFO - Running pre-action listener: _resource_record_pre_action 21:51:04 INFO - Running main action method: download_and_extract 21:51:04 INFO - mkdir: /builds/slave/test/build/tests 21:51: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')]} 21:51:04 INFO - https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:51:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 21:51:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 21:51:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:10 INFO - Downloaded 1199 bytes. 21:51:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 21:51:10 INFO - Using the following test package requirements: 21:51:10 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:51:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 21:51:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 21:51:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'jsshell-mac.zip'], 21:51:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 21:51:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:51:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 21:51:10 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 21:51:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 21:51:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:51:10 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 21:51:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:51:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:10 INFO - https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:51:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:51:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:51:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:12 INFO - Downloaded 18807073 bytes. 21:51: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'] 21:51: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 21:51:15 INFO - Return code: 0 21:51:15 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 21:51: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')]} 21:51:15 INFO - https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:51:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:51:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:51:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:16 INFO - Downloaded 18807073 bytes. 21:51:16 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'] 21:51:16 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 21:51:19 INFO - Return code: 0 21:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:19 INFO - https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:51:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 21:51:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 21:51:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:21 INFO - Downloaded 14403333 bytes. 21:51:21 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'] 21:51:21 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 21:51:22 INFO - Return code: 0 21:51:22 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')]} 21:51:22 INFO - https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:51:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:51:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:51:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 21:51:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/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 21:51:28 INFO - Downloaded 112479252 bytes. 21:51:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:51:28 INFO - mkdir: /builds/slave/test/properties 21:51:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:51:28 INFO - Writing to file /builds/slave/test/properties/build_url 21:51:28 INFO - Contents: 21:51:28 INFO - build_url:https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:51:28 INFO - Running post-action listener: _resource_record_post_action 21:51:28 INFO - Running post-action listener: set_extra_try_arguments 21:51:28 INFO - ##### 21:51:28 INFO - ##### Finished download-and-extract step (success) 21:51:28 INFO - ##### 21:51:28 INFO - ##### 21:51:28 INFO - ##### Running populate-webroot step. 21:51:28 INFO - ##### 21:51:28 INFO - Running pre-action listener: _resource_record_pre_action 21:51:28 INFO - Running main action method: populate_webroot 21:51:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:51:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:51:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:51:28 INFO - '--tppagecycles', 21:51:28 INFO - '7'], 21:51:28 INFO - 'tests': ['tsvgm']}}, 21:51:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:51:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:51:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:51:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:51:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:51:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:51:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['damp', 'tps']}, 21:51:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'tests': ['damp', 'tps']}, 21:51:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['dromaeo_dom']}, 21:51:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:51:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['basic_compositor_video']}, 21:51:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:51:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'other-e10s': {'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['a11yr', 21:51:28 INFO - 'ts_paint', 21:51:28 INFO - 'tpaint', 21:51:28 INFO - 'sessionrestore', 21:51:28 INFO - 'sessionrestore_no_auto_restore', 21:51:28 INFO - 'tabpaint']}, 21:51:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['tsvgx', 21:51:28 INFO - 'tsvgr_opacity', 21:51:28 INFO - 'tart', 21:51:28 INFO - 'tscrollx', 21:51:28 INFO - 'cart']}, 21:51:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:51:28 INFO - 'tsvgr_opacity', 21:51:28 INFO - 'tart', 21:51:28 INFO - 'tscrollx', 21:51:28 INFO - 'cart']}, 21:51:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'talos_options': ['--disable-e10s'], 21:51:28 INFO - 'tests': ['tp5o']}, 21:51:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'tests': ['tp5o']}, 21:51:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'talos_options': ['--disable-e10s', 21:51:28 INFO - '--xperf_path', 21:51:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:51:28 INFO - 'tests': ['tp5n']}, 21:51:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:51:28 INFO - 'talos_options': ['--xperf_path', 21:51:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:51:28 INFO - 'tests': ['tp5n']}}, 21:51:28 INFO - 'talos.zip': {'path': '', 21:51:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:51:28 INFO - Running post-action listener: _resource_record_post_action 21:51:28 INFO - ##### 21:51:28 INFO - ##### Finished populate-webroot step (success) 21:51:28 INFO - ##### 21:51:28 INFO - ##### 21:51:28 INFO - ##### Running create-virtualenv step. 21:51:28 INFO - ##### 21:51:28 INFO - Running pre-action listener: _resource_record_pre_action 21:51:28 INFO - Running main action method: create_virtualenv 21:51:28 INFO - Creating virtualenv /builds/slave/test/build/venv 21:51:28 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 21:51:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:51:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:51:28 INFO - Using real prefix '/tools/python27' 21:51:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:51:29 INFO - Installing distribute.............................................................................................................................................................................................done. 21:51:32 INFO - Installing pip.................done. 21:51:32 INFO - Return code: 0 21:51:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:51: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')]} 21:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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')]} 21:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51: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', 'pip>=1.5'] in /builds/slave/test/build 21:51: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 pip>=1.5 21:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:32 INFO - 'HOME': '/Users/cltbld', 21:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:32 INFO - 'LOGNAME': 'cltbld', 21:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:32 INFO - 'MOZ_NO_REMOTE': '1', 21:51:32 INFO - 'NO_EM_RESTART': '1', 21:51:32 INFO - 'PAGER': '/bin/cat', 21:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:32 INFO - 'PWD': '/builds/slave/test', 21:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:32 INFO - 'SHELL': '/bin/bash', 21:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:32 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:32 INFO - 'USER': 'cltbld', 21:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:32 INFO - 'XPC_FLAGS': '0x0', 21:51:32 INFO - 'XPC_SERVICE_NAME': '0', 21:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:32 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 21:51:32 INFO - Cleaning up... 21:51:32 INFO - Return code: 0 21:51:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:51: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')]} 21:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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')]} 21:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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', '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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:51: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 psutil>=3.1.1 21:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:32 INFO - 'HOME': '/Users/cltbld', 21:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:32 INFO - 'LOGNAME': 'cltbld', 21:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:32 INFO - 'MOZ_NO_REMOTE': '1', 21:51:32 INFO - 'NO_EM_RESTART': '1', 21:51:32 INFO - 'PAGER': '/bin/cat', 21:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:32 INFO - 'PWD': '/builds/slave/test', 21:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:32 INFO - 'SHELL': '/bin/bash', 21:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:32 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:32 INFO - 'USER': 'cltbld', 21:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:32 INFO - 'XPC_FLAGS': '0x0', 21:51:32 INFO - 'XPC_SERVICE_NAME': '0', 21:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:33 INFO - Downloading/unpacking psutil>=3.1.1 21:51: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 21:51: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 21:51: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 21:51: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 21:51: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 21:51: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 21:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:51:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:51:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:51:35 INFO - Installing collected packages: psutil 21:51:35 INFO - Running setup.py install for psutil 21:51:35 INFO - building 'psutil._psutil_osx' extension 21:51:35 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 21:51:36 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 21:51:36 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 21:51:36 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 21:51:36 INFO - building 'psutil._psutil_posix' extension 21:51:36 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 21:51:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:51:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:51:36 INFO - ^ 21:51:36 INFO - 1 warning generated. 21:51:36 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 21:51:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:51:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:51:36 INFO - Successfully installed psutil 21:51:36 INFO - Cleaning up... 21:51:36 INFO - Return code: 0 21:51:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:51: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')]} 21:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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')]} 21:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:51: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 mozsystemmonitor==0.3 21:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:36 INFO - 'HOME': '/Users/cltbld', 21:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:36 INFO - 'LOGNAME': 'cltbld', 21:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:36 INFO - 'MOZ_NO_REMOTE': '1', 21:51:36 INFO - 'NO_EM_RESTART': '1', 21:51:36 INFO - 'PAGER': '/bin/cat', 21:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:36 INFO - 'PWD': '/builds/slave/test', 21:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:36 INFO - 'SHELL': '/bin/bash', 21:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:36 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:36 INFO - 'USER': 'cltbld', 21:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:36 INFO - 'XPC_FLAGS': '0x0', 21:51:36 INFO - 'XPC_SERVICE_NAME': '0', 21:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:51:37 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 21:51:37 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 21:51:37 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 21:51:37 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 21:51:37 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 21:51:37 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 21:51:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:51:39 INFO - Installing collected packages: mozsystemmonitor 21:51:39 INFO - Running setup.py install for mozsystemmonitor 21:51:39 INFO - Successfully installed mozsystemmonitor 21:51:39 INFO - Cleaning up... 21:51:39 INFO - Return code: 0 21:51:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:51:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:39 INFO - 'HOME': '/Users/cltbld', 21:51:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:39 INFO - 'LOGNAME': 'cltbld', 21:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:39 INFO - 'MOZ_NO_REMOTE': '1', 21:51:39 INFO - 'NO_EM_RESTART': '1', 21:51:39 INFO - 'PAGER': '/bin/cat', 21:51:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:39 INFO - 'PWD': '/builds/slave/test', 21:51:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:39 INFO - 'SHELL': '/bin/bash', 21:51:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:39 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:39 INFO - 'USER': 'cltbld', 21:51:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:39 INFO - 'XPC_FLAGS': '0x0', 21:51:39 INFO - 'XPC_SERVICE_NAME': '0', 21:51:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:40 INFO - Downloading/unpacking jsonschema==2.5.1 21:51:40 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 21:51:40 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 21:51:40 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 21:51:40 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 21:51:40 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 21:51:40 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 21:51:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:51:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:42 INFO - Downloading functools32-3.2.3-2.tar.gz 21:51:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:51:42 INFO - warning: no files found matching '*.txt' 21:51:42 INFO - no previously-included directories found matching 'build' 21:51:42 INFO - no previously-included directories found matching 'dist' 21:51:42 INFO - no previously-included directories found matching '.git*' 21:51:42 INFO - Installing collected packages: jsonschema, functools32 21:51:42 INFO - Running setup.py install for functools32 21:51:43 INFO - warning: no files found matching '*.txt' 21:51:43 INFO - no previously-included directories found matching 'build' 21:51:43 INFO - no previously-included directories found matching 'dist' 21:51:43 INFO - no previously-included directories found matching '.git*' 21:51:43 INFO - Successfully installed jsonschema functools32 21:51:43 INFO - Cleaning up... 21:51:43 INFO - Return code: 0 21:51:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:51:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:51:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:43 INFO - 'HOME': '/Users/cltbld', 21:51:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:43 INFO - 'LOGNAME': 'cltbld', 21:51:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:43 INFO - 'MOZ_NO_REMOTE': '1', 21:51:43 INFO - 'NO_EM_RESTART': '1', 21:51:43 INFO - 'PAGER': '/bin/cat', 21:51:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:43 INFO - 'PWD': '/builds/slave/test', 21:51:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:43 INFO - 'SHELL': '/bin/bash', 21:51:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:43 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:43 INFO - 'USER': 'cltbld', 21:51:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:43 INFO - 'XPC_FLAGS': '0x0', 21:51:43 INFO - 'XPC_SERVICE_NAME': '0', 21:51:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:43 INFO - Downloading/unpacking blobuploader==1.2.4 21:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:51:45 INFO - Downloading blobuploader-1.2.4.tar.gz 21:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:51:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:51:45 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 21:51:45 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 21:51:45 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 21:51:45 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 21:51:45 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 21:51:45 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 21:51:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:51:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:46 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 21:51:47 INFO - Downloading docopt-0.6.1.tar.gz 21:51:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:51:47 INFO - Installing collected packages: blobuploader, requests, docopt 21:51:47 INFO - Running setup.py install for blobuploader 21:51:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:51:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:51:47 INFO - Running setup.py install for requests 21:51:47 INFO - Running setup.py install for docopt 21:51:47 INFO - Successfully installed blobuploader requests docopt 21:51:47 INFO - Cleaning up... 21:51:48 INFO - Return code: 0 21:51:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:48 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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:48 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 21:51:48 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 21:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:48 INFO - 'HOME': '/Users/cltbld', 21:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:48 INFO - 'LOGNAME': 'cltbld', 21:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:48 INFO - 'MOZ_NO_REMOTE': '1', 21:51:48 INFO - 'NO_EM_RESTART': '1', 21:51:48 INFO - 'PAGER': '/bin/cat', 21:51:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:48 INFO - 'PWD': '/builds/slave/test', 21:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:48 INFO - 'SHELL': '/bin/bash', 21:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:48 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:48 INFO - 'USER': 'cltbld', 21:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:48 INFO - 'XPC_FLAGS': '0x0', 21:51:48 INFO - 'XPC_SERVICE_NAME': '0', 21:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-14oY8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-mVuUSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nZJTsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-mLkkjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-v28Nvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-rBbsuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:51:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:51:48 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-guehLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BpkvlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ll9BxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XQLpP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-myRbzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FhpGoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-rAioZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-C5EtlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-f59H06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nxqZoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:51:49 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-9MBYw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:51:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:51:50 INFO - Running setup.py install for manifestparser 21:51:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:51:50 INFO - Running setup.py install for mozcrash 21:51:50 INFO - Running setup.py install for mozdebug 21:51:50 INFO - Running setup.py install for mozdevice 21:51:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:51:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:51:50 INFO - Running setup.py install for mozfile 21:51:50 INFO - Running setup.py install for mozhttpd 21:51:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:51:50 INFO - Running setup.py install for mozinfo 21:51:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Running setup.py install for mozInstall 21:51:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Running setup.py install for mozleak 21:51:51 INFO - Running setup.py install for mozlog 21:51:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Running setup.py install for moznetwork 21:51:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:51:51 INFO - Running setup.py install for mozprocess 21:51:51 INFO - Running setup.py install for mozprofile 21:51:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:51:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:51:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:51:52 INFO - Running setup.py install for mozrunner 21:51:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:51:52 INFO - Running setup.py install for mozscreenshot 21:51:52 INFO - Running setup.py install for moztest 21:51:52 INFO - Running setup.py install for mozversion 21:51:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:51:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:51:52 INFO - Cleaning up... 21:51:52 INFO - Return code: 0 21:51:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:52 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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:52 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 21:51:52 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 21:51:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:52 INFO - 'HOME': '/Users/cltbld', 21:51:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:52 INFO - 'LOGNAME': 'cltbld', 21:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:52 INFO - 'MOZ_NO_REMOTE': '1', 21:51:52 INFO - 'NO_EM_RESTART': '1', 21:51:52 INFO - 'PAGER': '/bin/cat', 21:51:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:52 INFO - 'PWD': '/builds/slave/test', 21:51:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:52 INFO - 'SHELL': '/bin/bash', 21:51:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:52 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:52 INFO - 'USER': 'cltbld', 21:51:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:52 INFO - 'XPC_FLAGS': '0x0', 21:51:52 INFO - 'XPC_SERVICE_NAME': '0', 21:51:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:51:52 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-E1eqxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-h0t_88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-YPZ1s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-GmE8bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-aGtFuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-gDOzg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-euVxhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-E55Rht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-lQT0gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-UQmNsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:51:53 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)) 21:51:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:51:53 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XJP8jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:51:54 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)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-0zFpo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-T2iuBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:51:54 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)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ubnIvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XzxLsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:51:54 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)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Dg5SbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:51:54 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)) 21:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:51:54 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FbM7NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:51:54 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)) 21:51:54 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)) 21:51:54 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)) 21:51:54 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)) 21:51:54 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)) 21:51:54 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)) 21:51:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:51:54 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 21:51:54 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 21:51:54 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 21:51:54 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 21:51:54 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 21:51:54 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 21:51:57 INFO - Downloading blessings-1.6.tar.gz 21:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:51:57 INFO - Installing collected packages: blessings 21:51:57 INFO - Running setup.py install for blessings 21:51:57 INFO - Successfully installed blessings 21:51:57 INFO - Cleaning up... 21:51:57 INFO - Return code: 0 21:51:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:51:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:51:57 INFO - Reading from file tmpfile_stdout 21:51:57 INFO - Current package versions: 21:51:57 INFO - blessings == 1.6 21:51:57 INFO - blobuploader == 1.2.4 21:51:57 INFO - docopt == 0.6.1 21:51:57 INFO - functools32 == 3.2.3-2 21:51:57 INFO - jsonschema == 2.5.1 21:51:57 INFO - manifestparser == 1.1 21:51:57 INFO - mozInstall == 1.12 21:51:57 INFO - mozcrash == 0.17 21:51:57 INFO - mozdebug == 0.1 21:51:57 INFO - mozdevice == 0.48 21:51:57 INFO - mozfile == 1.2 21:51:57 INFO - mozhttpd == 0.7 21:51:57 INFO - mozinfo == 0.9 21:51:57 INFO - mozleak == 0.1 21:51:57 INFO - mozlog == 3.2 21:51:57 INFO - moznetwork == 0.27 21:51:57 INFO - mozprocess == 0.23 21:51:57 INFO - mozprofile == 0.28 21:51:57 INFO - mozrunner == 6.12 21:51:57 INFO - mozscreenshot == 0.1 21:51:57 INFO - mozsystemmonitor == 0.3 21:51:57 INFO - moztest == 0.7 21:51:57 INFO - mozversion == 1.4 21:51:57 INFO - psutil == 3.1.1 21:51:57 INFO - requests == 1.2.3 21:51:57 INFO - wsgiref == 0.1.2 21:51:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:51:57 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')]} 21:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:57 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')]} 21:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:57 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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:57 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 21:51:57 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 21:51:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:57 INFO - 'HOME': '/Users/cltbld', 21:51:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:57 INFO - 'LOGNAME': 'cltbld', 21:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:57 INFO - 'MOZ_NO_REMOTE': '1', 21:51:57 INFO - 'NO_EM_RESTART': '1', 21:51:57 INFO - 'PAGER': '/bin/cat', 21:51:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:57 INFO - 'PWD': '/builds/slave/test', 21:51:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:57 INFO - 'SHELL': '/bin/bash', 21:51:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:57 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:57 INFO - 'USER': 'cltbld', 21:51:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:57 INFO - 'XPC_FLAGS': '0x0', 21:51:57 INFO - 'XPC_SERVICE_NAME': '0', 21:51:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 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)) 21:51:58 INFO - Cleaning up... 21:51:58 INFO - Return code: 0 21:51:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:51:58 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')]} 21:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:58 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')]} 21:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:51:58 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 0x106733a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067c8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94b9c9c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, '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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 21:51:58 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 21:51:58 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 21:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:51:58 INFO - 'HOME': '/Users/cltbld', 21:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:51:58 INFO - 'LOGNAME': 'cltbld', 21:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:51:58 INFO - 'MOZ_NO_REMOTE': '1', 21:51:58 INFO - 'NO_EM_RESTART': '1', 21:51:58 INFO - 'PAGER': '/bin/cat', 21:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:51:58 INFO - 'PWD': '/builds/slave/test', 21:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:51:58 INFO - 'SHELL': '/bin/bash', 21:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:51:58 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:51:58 INFO - 'USER': 'cltbld', 21:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:51:58 INFO - 'XPC_FLAGS': '0x0', 21:51:58 INFO - 'XPC_SERVICE_NAME': '0', 21:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:51:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:51:58 INFO - Cleaning up... 21:51:58 INFO - Return code: 0 21:51:58 INFO - Running post-action listener: _resource_record_post_action 21:51:58 INFO - Running post-action listener: _start_resource_monitoring 21:51:58 INFO - Starting resource monitoring. 21:51:58 INFO - ##### 21:51:58 INFO - ##### Finished create-virtualenv step (success) 21:51:58 INFO - ##### 21:51:58 INFO - ##### 21:51:58 INFO - ##### Running install step. 21:51:58 INFO - ##### 21:51:58 INFO - Running pre-action listener: _resource_record_pre_action 21:51:58 INFO - Running main action method: install 21:51:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:51:58 INFO - Reading from file tmpfile_stdout 21:51:58 INFO - Detecting whether we're running mozinstall >=1.0... 21:51:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:51:58 INFO - Reading from file tmpfile_stdout 21:51:58 INFO - Output received: 21:51:58 INFO - Usage: mozinstall [options] installer 21:51:58 INFO - Options: 21:51:58 INFO - -h, --help show this help message and exit 21:51:58 INFO - -d DEST, --destination=DEST 21:51:58 INFO - Directory to install application into. [default: 21:51:58 INFO - "/builds/slave/test"] 21:51:58 INFO - --app=APP Application being installed. [default: firefox] 21:51:58 INFO - mkdir: /builds/slave/test/build/application 21:51:58 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'] 21:51:58 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 21:52:32 INFO - Reading from file tmpfile_stdout 21:52:32 INFO - Output received: 21:52:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:52:32 INFO - Running post-action listener: _resource_record_post_action 21:52:32 INFO - ##### 21:52:32 INFO - ##### Finished install step (success) 21:52:32 INFO - ##### 21:52:32 INFO - ##### 21:52:32 INFO - ##### Running run-tests step. 21:52:32 INFO - ##### 21:52:32 INFO - Running pre-action listener: _resource_record_pre_action 21:52:32 INFO - Running main action method: run_tests 21:52:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:52:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:52:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:52:32 INFO - 2016-07-08 21:52:32.851 screenresolution[1651:8582] starting screenresolution argv=screenresolution get 21:52:32 INFO - 2016-07-08 21:52:32.854 screenresolution[1651:8582] Display 0: 1600x1200x32@60 21:52:32 INFO - 2016-07-08 21:52:32.859 screenresolution[1652:8584] starting screenresolution argv=screenresolution list 21:52:32 INFO - Available Modes on Display 0 21:52:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:52:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:52:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:52:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:52:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:52:33 INFO - Intel Iris: 21:52:33 INFO - Chipset Model: Intel Iris 21:52:33 INFO - Type: GPU 21:52:33 INFO - Bus: Built-In 21:52:33 INFO - VRAM (Dynamic, Max): 1536 MB 21:52:33 INFO - Vendor: Intel (0x8086) 21:52:33 INFO - Device ID: 0x0a2e 21:52:33 INFO - Revision ID: 0x0009 21:52:33 INFO - Displays: 21:52:33 INFO - Display: 21:52:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:52:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:52:33 INFO - Display Serial Number: 1600x1200 60 21:52:33 INFO - Main Display: Yes 21:52:33 INFO - Mirror: Off 21:52:33 INFO - Online: Yes 21:52:33 INFO - Rotation: Supported 21:52:33 INFO - Return code: 0 21:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:52:33 INFO - Python 2.7.3 21:52:33 INFO - Return code: 0 21:52:33 INFO - grabbing minidump binary from tooltool 21:52:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:52:33 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 0x106697d30>, '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 0x1063fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10668fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:52:33 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 21:52:33 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 21:52:33 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 21:52:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:52:33 INFO - Return code: 0 21:52:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:52:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:52:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:52:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:52:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:52:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 21:52:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 21:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ffOMNz8etR/Render', 21:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:52:33 INFO - 'HOME': '/Users/cltbld', 21:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:52:33 INFO - 'LOGNAME': 'cltbld', 21:52:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:52:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:33 INFO - 'MOZ_NO_REMOTE': '1', 21:52:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:52:33 INFO - 'NO_EM_RESTART': '1', 21:52:33 INFO - 'PAGER': '/bin/cat', 21:52:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:33 INFO - 'PWD': '/builds/slave/test', 21:52:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:52:33 INFO - 'SHELL': '/bin/bash', 21:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QEO6ZQSnM6/Listeners', 21:52:33 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 21:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:52:33 INFO - 'USER': 'cltbld', 21:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:33 INFO - 'XPC_FLAGS': '0x0', 21:52:33 INFO - 'XPC_SERVICE_NAME': '0', 21:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:52:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 21:52:33 INFO - mozversion application_buildid: 20160708201526 21:52:33 INFO - mozversion application_changeset: 57d42a4e2ff190b3699fd210891bd5026cc40c22 21:52:33 INFO - mozversion application_display_name: Nightly 21:52:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:52:33 INFO - mozversion application_name: Firefox 21:52:33 INFO - mozversion application_remotingname: firefox 21:52:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:52:33 INFO - mozversion application_vendor: Mozilla 21:52:33 INFO - mozversion application_version: 50.0a1 21:52:33 INFO - mozversion platform_buildid: 20160708201526 21:52:33 INFO - mozversion platform_changeset: 57d42a4e2ff190b3699fd210891bd5026cc40c22 21:52:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:52:33 INFO - mozversion platform_version: 50.0a1 21:52:33 INFO - using testdate: 1468039953 21:52:33 INFO - actual date: 1468039953 21:52:33 INFO - starting webserver on 'localhost:49271' 21:52:33 INFO - SUITE-START | Running 2 tests 21:52:33 INFO - TEST-START | dromaeo_css 21:52:33 INFO - operating with platform_type : mac_ 21:52:33 INFO - Initialising browser for dromaeo_css test... 21:52:33 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpnQdgCR/profile http://localhost:49271/getInfo.html) 21:52:35 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 21:52:35 INFO - PROCESS | 1662 | colorDepth:24 21:52:35 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 21:52:35 INFO - PROCESS | 1662 | __metrics 21:52:35 INFO - PROCESS | 1662 | 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" 21:52:35 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:52:36 INFO - TEST-INFO | 1662: exit 0 21:52:36 INFO - Browser initialized. 21:52:36 INFO - Running cycle 1/1 for dromaeo_css test... 21:52:36 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpnQdgCR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:52:48 INFO - PROCESS | 1665 | RSS: Main: 243769344 21:52:48 INFO - PROCESS | 1665 | 21:53:40 INFO - PROCESS | 1665 | 1468040020187 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] 21:55:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 21:55:52 INFO - PROCESS | 1665 | RSS: Main: 385814528 21:55:52 INFO - PROCESS | 1665 | 21:58:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 21:58:55 INFO - PROCESS | 1665 | RSS: Main: 381612032 21:58:55 INFO - PROCESS | 1665 | 22:01:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:01:58 INFO - PROCESS | 1665 | RSS: Main: 373727232 22:01:58 INFO - PROCESS | 1665 | 22:05:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:05:01 INFO - PROCESS | 1665 | RSS: Main: 371445760 22:05:01 INFO - PROCESS | 1665 | 22:08:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 22:08:05 INFO - PROCESS | 1665 | RSS: Main: 342638592 22:08:05 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 22:11:08 INFO - PROCESS | 1665 | RSS: Main: 432443392 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | __start_tp_report 22:11:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 22:11:08 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 22:11:08 INFO - PROCESS | 1665 | |i|pagename|runs| 22:11:08 INFO - PROCESS | 1665 | |0;dojo.html;3349.78226415283;3362.8822991195298;3366.7306615470766;3389.593248810143;3418;5583.553315734742;5645.407232240617;5673.8297851064535;5733.5126514246285;5755.395683453235;5656.547476201915;5672.404397538261;5808.448197421249;5816.680082595455;5848.298204215489;5105.923411148836;5125;5201.661891977028;5215.191645294985;5312.389075256337;5296.735163241842;5329.094054010828;5334.91997620036;5345.224942383352;5376.193570964348;5140.897182056376;5141.82003629873;5188.533032027098;5201.323827902386;5217.399999000107;5158.974205128988;5163.5352818246165;5173.275741396207;5250.422453530108;5327.280817089682;5150.021495915764;5191.169412893919;5192.7403629818355;5265.578753699695;5272.209168624699;4649.814007439699;5624.578156638268;5738.770449182028;5819.388964158745;5820.44705752957;6221.786751583443;6228.377162283736;6249.343818899041;6257.906131408033;6272.717727702265;6245.594036387621;6259.405356491172;6262.248530176363;6269.937300626981;6281.246250449975;5636.070048442043;5699.430056994351;5715.657060576295;5793.942060579382;5868.972929737279;6231.034189700604;6238.1890354254565;6287.622742635456;6288.685565721696;6297.338779428185;6119.9902016167725;6126.6017708848785;6128.938710612926;6143.648397352576;6150.938490615036;6110.7555697771595;6139.140520327158;6178.938210617836;6274.592151510228;6276.372362763687;6153.630782153085;6206.348333425016;6266.091416744638;6272.1219029335925;6273.341299163522;6276.686165691697;6290.276618188876;6297.811065668037;6299.716512756937;6313.747450102036;4830.3479030331155;4838.637102217277;4923.532264434873;4924.493425871063;4963.404391472975;4396.230659634552;4464.531224221475;4482.910341793146;4487.955120448753;4493.707908985971;5722.942770572325;5728.627639203439;5732.885342293131;5735.8566035849435;5745.023346031185;6159.414855588712;6216.968915155486;6223.595466294676;6227.408396202353;6232.719527621268;5766.279215098113;5774.740136693859;5804.245448091806;5805.419458054245;5814.273215848019;5454.563634909197;5459.972700136554;5475.09660905954;5477;5478.534324582375;6148.047052706837;6221.595596286227;6233.844153896189;6276.686165691788;6324.272708638381;6255.40573645503;6262.874742505215;6280.623162610258;6282.937170628418;6295.55931084838;6163.8150855474405;6339.080833279057;6343.682815859281;6344.175257216532;6351.9047214290895;5045.621578381562;6143.416375444325;6146.815595532141;6248.125262463168;6250.468710159687;6277.5605707600025;6285.874282514415;6287.528435367457;6290.433860952537;6299.149614801943;5968.015277479168;6053.9091913622215;6181.196444462191;6189.721462534287;6192.535559833121;4820.060088282891;6076.118962750465;6096.993995990611;6124.6325220486915;6146.231721034871;5920.437558431942;5973.432523910222;5993.041113421831;6012.218411606463;6060.878782424239;5981.923253814355;6119.380693981147;6135.14108024868;6149.538784591084;6165.982612868826;4837.2260438329695;4956.677815941952;5947.04847224442;5957.821265362046;5998.70006499682 22:11:08 INFO - PROCESS | 1665 | |1;ext.html;486.4551702093656;526.0714838310381;529.2061907139291;542.7150745858423;551.9448055194483;30385.08844734656;35280.294394112105;36155;36216.45675314873;38235.42646860292;44902.775486122526;45149.7742511287;45386.63840084794;45415.54584454154;45477.54522454774;8795.824083518326;9745.658901938452;9888.505574721272;9905.30662853597;9914.157296629795;8813.779655508632;9048.095190480932;9050.1854833065;9066.954665226665;11801.232919860182;11948.70128246792;12200.023998080133;12213.206141600813;12223.755524889497;12233.388330583479;12057.638270851889;12120.212186207957;12135.08986825987;12139.15025948184;12143;8753.518556479392;11659.883401165964;11905.642830715005;11916.642500724993;11987;42851.57148428507;43101.35347969783;43168.78415607902;43211.919702007384;43305.78347108276;3439.4496880499;3502.544669193014;3522.2427178156818;3685.4103343465185;3784.3566593679143;25362.365940851625;25773.35566610831;32432.83783581067;32685.50971735426;33488.99533014013;3245.107595411257;3394.2702319001287;3412.607550131742;3416.350893330284;3431.3823511767873;456901.71549142373;457619;462277;462942;470260.64869675774;60523.69738151281;61335.38664613386;61362.38637613656;61381;61411.69294153501;64366.678166608865;64669;64672.67663661652;64844;64890.67554662291;14233.928830355782;16530.92548984312;16531.752023719655;16532;16585.087820169767;12849.229046257255;12886.80669789954;12912;12913.289769062792;12975.610731678065;14245.715085698228;16094.436694715863;16141.515754527383;16174.838251617568;16242.51272461828;6337.271213810379;7304.561726296439;7330.230325815819;7367.447441441914;7418.183999760022;957.4686049242598;980.4460479828874;1065.174489770434;1088.6679562733443;1125.763589646167;29305.85347073251;29826;30727.38545229083;32466.35067298641;32731;959.8464245720651;1082.7130810335268;1090.9018188363086;1158.959436419738;1198.083466148397;15190.316435760416;15277.465288714842;15280.541583752505;15345.668738071134;15410.383584656713;626884;632160;632442;632483;632553;42825.92935176583;50181.74909125431;53730.46269537333;55846.441535584934;57045;1222.8104643780227;1415.6319356967056;1438.1663379191614;1445.2773613193403;1448.2613866927943;26315;29893.402131957675;30402.9921822297;32007.519887202176;32477.350452990337;55654.72172639111;56363.71818140883;57020.714896425256;57164.1425378628;57196.71401642965;624097;630079;630256;631018;631254;24733.639649819434;25880.352991175372;26260.868695656398;26310.73689263083;26347.73652263453;47747.761261194864;48758.26862597135;48766.26850597315;48792.512074880215;48797.780055497475;39749;40027.39958900677;40131.39802903017;40361.59638403696;41116.588834112474;39809.20381592294;40142.59857401506;40154.799226003684;40750;40904.38643420291;38414.615853842224;38492.80753596214;38737;38926.610733892296;38960.83117506479;29513.409731805674;34552.65447345595;35380.64619353774;35725.64274357223;36012.63987360093;25331.74668253294;25477;25627.462352258925;25882.31764935273;26690.332741681614 22:11:08 INFO - PROCESS | 1665 | |2;jquery.html;362.8657396763198;386.36057325126916;390.8885967499265;392.39179272128194;416.16974136597474;13220.801687974688;15414.229288535571;19953.60092798145;21725.45686357842;22088.779112208875;20785.792142078575;21192.364229073115;21618.243361482353;21862.344129676134;21912.013959371827;5946.137810017555;6124.5100391968535;6162.1681073055015;6163.845903852417;6167.660778657172;5539.695316757577;5803.767849286023;5954.642721436706;5962.896864080138;5975.014122669754;5803.419658034205;5885.823425297248;5914.4381283777975;5925.762969481216;5928.05151175812;5741.081426971686;5874.853128671774;5901.321348044987;5916.497097746654;5919.171316015782;5122.33409656746;5186.299849520305;5192.844214673566;5192.92210616841;5199.532042116228;20129.496762580908;20298.49253768653;25285.620715689278;25949.22152335433;26218.21345359642;2154.881481518516;2250.493638931244;2283.9657405138937;2334.2647066174272;2341.9297421077395;10007.49962501872;10245.79508409835;10400.219983501192;10726.069307524642;16051.759223611654;1834.110456428631;1865.7947625761155;1870.8035656256034;1884.2463014794055;1912.2733361322607;79311.20688793153;80061;107179;133196.00205996918;135725.3213733925;10506.842397364046;11223;11498.597549085742;11674.474648640831;11923.986461150726;13739.519116830863;14342.928285358506;14523.709525809425;14531.92734036323;14546.199959002144;6383.074454204115;6531.673416329165;6650.900236496456;7430.702771889172;7874.133845277015;7520.699172033167;7590.278923502258;7960.363170946311;7983.241592048862;8619.827603447897;4354.477462704495;4531.932021019687;4589.54104589545;4600.631949444037;4622.699524530962;1897.345415831536;1926.3257859749256;1930.0928563575096;1934.6324198402258;1954.452753229098;909.6543313540813;961.9899105938737;978.280963491833;995.7062666513395;1002.5087707023506;20383.388498344775;21154.365369038955;22117.33647990563;22147.778522214892;22525.12118449448;753.9849203015909;957.9568919398644;978.6281213139104;979.4417182206074;1000.564754331868;10859.565617375374;11303;11368.772624547462;11733.823992640117;11839.408029598486;592544;629744;636831;650255;661077;111399;152921;153168;155230.2238488823;155460;885.6988623867787;967.351874244258;994.4580203788952;1058.4760543530847;1060.1201003167353;19871.503212419804;20323.79676203219;20412.59174816525;20468.283610074173;20591.588168236853;43502.782486087366;56911.71544142253;57371;57493.71253143708;59021.70489147527;82783.17216827755;82847.5857620708;83563.16436835554;84750;85016;7156.785296441115;7592.696292148473;8187.877181842158;8310.750677479686;8550.957245213734;12301.87698123043;12855.742885142432;13573.796393053915;13691.863081369058;13779.931100344435;11589.884101158881;11616.535338586362;11828.76342473163;11893.583724569598;11918.463669134737;9617.182539484222;11570.537178512765;11580.710482238012;11652.650420487398;11787.587434440096;11876.216620913401;11900.40497975115;12047.879521204675;12069.75860482803;12072.33602151855;7598.734044308423;7684.4236682250175;7686.923130768844;7710.151883292721;7786.299233068825;11277.492512837107;11595.710107247049;11850.881491184979;11870.931616154587;11900.523979041087 22:11:08 INFO - PROCESS | 1665 | |3;mootools.html;1127.5151684775544;1556.6964441933824;1583.1688363609103;1816.536783120304;1956.0708663384885;7713.344365728913;8182.91817081829;8195.344372450205;8196.057453392854;8230.2592766651;8414;8662;8669.176428239307;8819.470831750099;8844.248238899685;2334.4630734930934;2401.987990060047;2403.098837935774;2404.1585445094165;2405.482821193443;2362.456634973957;2380.547695937766;2397.1729753235104;2403.6995375578053;2405.6632071510003;2360.598698221298;2375.714914210297;2384.9761502384927;2397.1729753235104;2397.8441401308946;2311.0986715181093;2330.883455827202;2352.5765362234793;2360.5986982213067;2364.456175079741;1949.6783030799902;2362.716474023111;2362.7282862470865;2362.7637236276237;2370.111208296889;8850.557472126367;8854.203121719076;8956.373053886231;8965.910340896573;8968.968568614628;5184.300119483859;5248.396434410015;5357.598180136505;5419.729013549346;5454.53636440903;3524.788512689247;3579.49887015818;3652.36083685354;3652.5799533053773;3659.121810765397;921.0973246218743;944.8866136063648;951.5908159491416;955.9808803823885;959.2326139088701;11570.421478926104;11732.882671173349;11741.647750567497;11749.29504229749;11772.823407648977;4440.800163992628;4485.147821913826;4658.67389282747;4666.2067448533835;4675.719456832601;9228.7692807679;9237.214836738818;9250.4912229828;9253.629854805733;9259.351845370895;5281.207818827206;5282.128448805981;5290.867728306746;5295.338082739658;5305.814296499604;4447.288433850568;4590.609798167193;4770.6660533763;4920.384951881015;4960.950390496121;3758.981205093957;3775.0373654717873;3850.9037274067814;3879.0690234343556;3888.7083468739957;1169.602335206017;1185.6028230542865;1185.8221266809876;1198.646399312205;1198.9460474278678;650.2619526837074;688.2738710660301;822.9300509456643;926.9165775080276;980.5195454227377;5531.419200983919;5911.3201981772645;6006.489448396936;6079.388961925093;6113.20528331323;471.73818530715783;478.6601512925792;487.1085912779605;498.4990084964587;627.1439485102802;3852.903677408031;3979.263836190314;3988.660963818108;4014.598540145962;4058.188362327546;10426.947865260625;10730.248882578144;10945.507452164671;10954.890451095387;10992.230543862013;10903.964123408263;10910;10911.781764364669;10916.617918372978;10937.671869843918;6868.7252509900045;8296.419250652518;8300.543470109202;8351.20663536963;8384.077751447336;5841.240638716939;5872.55955803325;5876.883392155477;5884.205632239764;5921.585489015728;10019.448930308903;10094.14199793026;10116.595336186765;10162.74593135148;10166.135878450119;9847.359921605073;10008.299419040597;10284.023017813295;10363.94818025905;10370.948145259526;6959.478039146983;7004.334588214111;7082.433405327459;7094.929050709633;7110.7511237106455;7482.251774822692;7918.881216781868;8292.751217463485;8401.369897257608;8402.731262559197;7590.8102297443;7608.961955190189;7616.352610027988;7719.729809456642;7743.380529557735;7341.522801017917;7437.70249190048;7508.061492313461;7558.773236802905;7569.3566046886635;7019.4033507150425;7025.718971241297;7129.322714342129;7139.214686384597;7151.356377926011;5056.469070747666;5060.2156665716875;5075.187969924795;5144.922826157685;5144.9742751286;4730.455997560324;5030.874228144326;5061.898762024665;5204.297419848272;5228.58171346299 22:11:08 INFO - PROCESS | 1665 | |4;prototype.html;291.33284777858705;301.40322162119;306.4820453433697;308.43556291985664;309.16371215861096;4243.724157929732;5059.59523238141;5285.709285989274;5311.601629877765;5326.786928522859;3953.9522026662885;4561.791125351784;4957.5786058185095;5045.77294021769;5257.553107985826;3557.92884142317;4006.559278479365;4031.911383926338;4038.7576745395295;4056;3625.329314076891;3892.143728379766;4074.9592504075026;4106.8973275668195;4126.834926602933;3799.6580307772297;3875;4045.858394956177;4053.3312003519386;4060;3360.630330663625;3987.142764305688;3988.641022307977;4001.639852413297;4006.659433948118;3102.3784901757977;3401.4047541680357;3489.342562282921;3538.0800991742076;3539.185987222953;4970.304157417964;5184.792608295664;5194.792208311663;5199.922001169985;5207.817726379596;4086.5300490443383;4497.078098989699;4539.954600453986;4588.403507543998;4608.400907881987;5066.238899799618;5254;5429.185622156669;5504.311961004874;5514.6691198528215;3842.8462861485787;3857.5756666766633;3866.6906647468136;3877.507159743519;3880.8835734927716;5222.738863056832;5277.656952298088;5489.835304940858;5670.262865827457;5775.884482310373;3303.0916497963253;3321.8276545898607;3718.721095917817;3837.808109594565;3911.6088391160656;3925;3971.225611005826;3983.701222408331;3987.980060099739;4002.8398864045694;3932.4018523051086;4277.946585638911;4436.600705936481;4583.427071616048;4596.47140578831;5529.834104976857;5532.2531458253425;5536.86494268674;5544.500994910397;5640.407757185519;1224.222618637154;1339.912905661129;1365.6312795545148;1373.9725205495834;1374.216696483015;671.5366397185926;765.9774201441027;834.870595057767;837.8031162676766;847.4915050969493;4529.881234205327;5037.722925239147;5057.696538207646;5072.442031376546;5083.415407228216;5587.441255874462;5593.9861649806035;5616.522595579421;5687.317521897398;5729.255196824386;4611.654125940501;4824.710517368969;4839.68542044773;4853.781579828917;5122.590192784568;2996.4905965985836;3034.332446861687;3042.9847850760602;3046.908592742221;3067.7852550321263;4857.417109946829;5142.631576317518;5817.778266563984;5920.348761636216;6005.6997150142315;5054.393472783289;5680.494668912742;5851.941480585225;5855.473007429352;5882.558738123631;5584.944150558524;5906.26171728534;5907.645541267538;5927.911081333869;5956.434138756811;5392.784288628411;5711;5759.034097439906;5777.566682498746;5796.0293500208445;4793.520647935178;5351.464853514617;5653.378128405954;5654.208410822571;5663.3770285267765;5002.624803139706;5382.865428364322;5726.226959360433;5841.101641966293;5889.8770860398745;3531.399662057405;3775.6413140751583;3789.090618251655;3792.4690543323404;3802.695784337191;4433.091216300715;5756.30924289088;5757.5393968481585;5779.884402312015;5804.939246567408;5179.741012949413;5516.793282686797;5546.278983732089;5597.44025597437;5629.521490673174;4992.101421744121;5097.464766199644;5293.894122117613;5436.918446223231;5573.693446860461;4376.868693939187;4796.064767370469;5115.74421278942;5480.095784195563;5492.121260598445;5261.500157485033;5493.587980901369;5496.1755736639825;5539.556835453073;5562.72186390687;4950.356453661001;5059.772310245974;5403.27134796925;5448.392724146307;5483.616146869681 22:11:08 INFO - PROCESS | 1665 | |5;yui.html;594.8192837218122;690.5890994858057;702.3748863511474;770.7957391291309;840.4453060979755;14866.925665371671;16195.595110122253;16244;16246.02523848567;16273.837261627395;12543;14789.260536973163;14886.553403397887;14966.401343946256;14998.850011499882;701.9964900175491;723.634564544904;726.5531698005715;728.50097683087;891.8974317953438;721.163450397539;723.7249845058897;894.592960203106;921.1847514949251;924.1728652855691;964.1756298364867;969.4474149734654;970.3207754571828;971.8639390485338;973.9951300243524;843.2284459719349;937.9541810880887;948.1750876737249;963.6530848894428;969.5443141723379;557.4843269975256;704.4962851561107;722.4725950056436;723.3815088099651;728.2717282717283;15435.845641543554;15467.303971321318;15471.381144754196;15512.689746205013;15514.301856416489;14140.505082322172;14578.562643120724;14616.488422905148;14662;14676.412943482248;13120.86879131206;13206.537771177962;13245.337733113305;13411.865881341257;13813.585592432242;4675.532446755331;4691.7888695008805;4715.740634265114;4890.8043678252825;4929.1620424527555;111659.99999999919;117128;117809;117856.41071794672;118180;11540.826887596693;11711.648650540497;11735.061195104372;11791.056715462742;11877.22798018126;23632.29103126909;23656.881715591313;23678.171264005676;23687.81560921947;23696.7630323698;7891.881621775678;8141.104478507359;8150.021997360354;8181.713640022571;8451.197136272045;4528.47922488918;4583.862484125481;4630.652701047367;4708.576228139415;4801.087793319328;3511.964880351215;3642.1440961374055;3642.2897534980425;3648.945265821015;3650.3429382710838;607.9483243924316;666.6633350158236;668.0913957018452;672.6266921858415;696.6238231355012;617.3394467919284;622.3993845938601;645.1129696667083;647.6049609738056;652.4962926347009;7545.320921117126;8792.032876383593;9099.590518426687;9117.498537580497;9137.986891619215;526.6997811247629;557.7349817720703;564.6922427277061;581.3091318919186;583.4952765857503;3570.6607872252093;4205.111941407937;4400.889977750582;4439.311906654474;4499.4150760400935;121471;140437;143961.28019359836;147185;150273;80159;120043.39978300052;134141;134332;138589.999999998;102824;116394.41802791102;117268.41365793116;118539.40730296294;120080.99999999825;8609.65561377538;8643.91356086431;8790.42862213954;8798.824023519623;8889.288856891304;42806;53426.46573534215;63465;63471;66007.33992660012;74906.25093748992;76494.61752691424;87183;91256;93988;13242.735145297233;13584.981126415756;13612.38744256491;13705.109167904055;14170.362333694802;14814.333354998835;14862.256887155816;15075.924620376827;15211;15596.14221217844;11205.383703896365;13860.514881979083;13888.791668124784;13997.650058748206;14219.928900355431;13887.513937012156;13982.23097729594;14184.148951062969;14201.502947396792;14555.05392149507;13501.864981350454;13851.37668804886;13872.514461993782;13883;14211.715765684836;9297.535123243946;11075.612353567587;11302.660920172408;11537.307761534335;11655.125865560287;8097.8380432392205;9040.502772347583;10309.360231920988;11223.494942727433;11256.887431125911 22:11:08 INFO - PROCESS | 1665 | __end_tp_report 22:11:08 INFO - PROCESS | 1665 | __start_cc_report 22:11:08 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1415 22:11:08 INFO - PROCESS | 1665 | __end_cc_report 22:11:08 INFO - PROCESS | 1665 | __startTimestamp1468041068814__endTimestamp 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | ------- Summary: start ------- 22:11:08 INFO - PROCESS | 1665 | Number of tests: 6 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5731.01 Median:5968.02 stddev:652.29 (10.9%) stddev-sans-first:626.97 22:11:08 INFO - PROCESS | 1665 | Values: 3349.8 3362.9 3366.7 3389.6 3418.0 5583.6 5645.4 5673.8 5733.5 5755.4 5656.5 5672.4 5808.4 5816.7 5848.3 5105.9 5125.0 5201.7 5215.2 5312.4 5296.7 5329.1 5334.9 5345.2 5376.2 5140.9 5141.8 5188.5 5201.3 5217.4 5159.0 5163.5 5173.3 5250.4 5327.3 5150.0 5191.2 5192.7 5265.6 5272.2 4649.8 5624.6 5738.8 5819.4 5820.4 6221.8 6228.4 6249.3 6257.9 6272.7 6245.6 6259.4 6262.2 6269.9 6281.2 5636.1 5699.4 5715.7 5793.9 5869.0 6231.0 6238.2 6287.6 6288.7 6297.3 6120.0 6126.6 6128.9 6143.6 6150.9 6110.8 6139.1 6178.9 6274.6 6276.4 6153.6 6206.3 6266.1 6272.1 6273.3 6276.7 6290.3 6297.8 6299.7 6313.7 4830.3 4838.6 4923.5 4924.5 4963.4 4396.2 4464.5 4482.9 4488.0 4493.7 5722.9 5728.6 5732.9 5735.9 5745.0 6159.4 6217.0 6223.6 6227.4 6232.7 5766.3 5774.7 5804.2 5805.4 5814.3 5454.6 5460.0 5475.1 5477.0 5478.5 6148.0 6221.6 6233.8 6276.7 6324.3 6255.4 6262.9 6280.6 6282.9 6295.6 6163.8 6339.1 6343.7 6344.2 6351.9 5045.6 6143.4 6146.8 6248.1 6250.5 6277.6 6285.9 6287.5 6290.4 6299.1 5968.0 6053.9 6181.2 6189.7 6192.5 4820.1 6076.1 6097.0 6124.6 6146.2 5920.4 5973.4 5993.0 6012.2 6060.9 5981.9 6119.4 6135.1 6149.5 6166.0 4837.2 4956.7 5947.0 5957.8 5998.7 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:71078.05 Median:21009.89 stddev:154924.55 (737.4%) stddev-sans-first:155268.49 22:11:08 INFO - PROCESS | 1665 | Values: 486.5 526.1 529.2 542.7 551.9 30385.1 35280.3 36155.0 36216.5 38235.4 44902.8 45149.8 45386.6 45415.5 45477.5 8795.8 9745.7 9888.5 9905.3 9914.2 8813.8 9048.1 9050.2 9067.0 11801.2 11948.7 12200.0 12213.2 12223.8 12233.4 12057.6 12120.2 12135.1 12139.2 12143.0 8753.5 11659.9 11905.6 11916.6 11987.0 42851.6 43101.4 43168.8 43211.9 43305.8 3439.4 3502.5 3522.2 3685.4 3784.4 25362.4 25773.4 32432.8 32685.5 33489.0 3245.1 3394.3 3412.6 3416.4 3431.4 456901.7 457619.0 462277.0 462942.0 470260.6 60523.7 61335.4 61362.4 61381.0 61411.7 64366.7 64669.0 64672.7 64844.0 64890.7 14233.9 16530.9 16531.8 16532.0 16585.1 12849.2 12886.8 12912.0 12913.3 12975.6 14245.7 16094.4 16141.5 16174.8 16242.5 6337.3 7304.6 7330.2 7367.4 7418.2 957.5 980.4 1065.2 1088.7 1125.8 29305.9 29826.0 30727.4 32466.4 32731.0 959.8 1082.7 1090.9 1159.0 1198.1 15190.3 15277.5 15280.5 15345.7 15410.4 626884.0 632160.0 632442.0 632483.0 632553.0 42825.9 50181.7 53730.5 55846.4 57045.0 1222.8 1415.6 1438.2 1445.3 1448.3 26315.0 29893.4 30403.0 32007.5 32477.4 55654.7 56363.7 57020.7 57164.1 57196.7 624097.0 630079.0 630256.0 631018.0 631254.0 24733.6 25880.4 26260.9 26310.7 26347.7 47747.8 48758.3 48766.3 48792.5 48797.8 39749.0 40027.4 40131.4 40361.6 41116.6 39809.2 40142.6 40154.8 40750.0 40904.4 38414.6 38492.8 38737.0 38926.6 38960.8 29513.4 34552.7 35380.6 35725.6 36012.6 25331.7 25477.0 25627.5 25882.3 26690.3 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:36470.03 Median:2309.12 stddev:106019.97 (4591.4%) stddev-sans-first:106282.72 22:11:08 INFO - PROCESS | 1665 | Values: 362.9 386.4 390.9 392.4 416.2 13220.8 15414.2 19953.6 21725.5 22088.8 20785.8 21192.4 21618.2 21862.3 21912.0 5946.1 6124.5 6162.2 6163.8 6167.7 5539.7 5803.8 5954.6 5962.9 5975.0 5803.4 5885.8 5914.4 5925.8 5928.1 5741.1 5874.9 5901.3 5916.5 5919.2 5122.3 5186.3 5192.8 5192.9 5199.5 20129.5 20298.5 25285.6 25949.2 26218.2 2154.9 2250.5 2284.0 2334.3 2341.9 10007.5 10245.8 10400.2 10726.1 16051.8 1834.1 1865.8 1870.8 1884.2 1912.3 79311.2 80061.0 107179.0 133196.0 135725.3 10506.8 11223.0 11498.6 11674.5 11924.0 13739.5 14342.9 14523.7 14531.9 14546.2 6383.1 6531.7 6650.9 7430.7 7874.1 7520.7 7590.3 7960.4 7983.2 8619.8 4354.5 4531.9 4589.5 4600.6 4622.7 1897.3 1926.3 1930.1 1934.6 1954.5 909.7 962.0 978.3 995.7 1002.5 20383.4 21154.4 22117.3 22147.8 22525.1 754.0 958.0 978.6 979.4 1000.6 10859.6 11303.0 11368.8 11733.8 11839.4 592544.0 629744.0 636831.0 650255.0 661077.0 111399.0 152921.0 153168.0 155230.2 155460.0 885.7 967.4 994.5 1058.5 1060.1 19871.5 20323.8 20412.6 20468.3 20591.6 43502.8 56911.7 57371.0 57493.7 59021.7 82783.2 82847.6 83563.2 84750.0 85016.0 7156.8 7592.7 8187.9 8310.8 8551.0 12301.9 12855.7 13573.8 13691.9 13779.9 11589.9 11616.5 11828.8 11893.6 11918.5 9617.2 11570.5 11580.7 11652.7 11787.6 11876.2 11900.4 12047.9 12069.8 12072.3 7598.7 7684.4 7686.9 7710.2 7786.3 11277.5 11595.7 11850.9 11870.9 11900.5 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5674.48 Median:5058.34 stddev:3214.75 (63.6%) stddev-sans-first:3205.60 22:11:08 INFO - PROCESS | 1665 | Values: 1127.5 1556.7 1583.2 1816.5 1956.1 7713.3 8182.9 8195.3 8196.1 8230.3 8414.0 8662.0 8669.2 8819.5 8844.2 2334.5 2402.0 2403.1 2404.2 2405.5 2362.5 2380.5 2397.2 2403.7 2405.7 2360.6 2375.7 2385.0 2397.2 2397.8 2311.1 2330.9 2352.6 2360.6 2364.5 1949.7 2362.7 2362.7 2362.8 2370.1 8850.6 8854.2 8956.4 8965.9 8969.0 5184.3 5248.4 5357.6 5419.7 5454.5 3524.8 3579.5 3652.4 3652.6 3659.1 921.1 944.9 951.6 956.0 959.2 11570.4 11732.9 11741.6 11749.3 11772.8 4440.8 4485.1 4658.7 4666.2 4675.7 9228.8 9237.2 9250.5 9253.6 9259.4 5281.2 5282.1 5290.9 5295.3 5305.8 4447.3 4590.6 4770.7 4920.4 4961.0 3759.0 3775.0 3850.9 3879.1 3888.7 1169.6 1185.6 1185.8 1198.6 1198.9 650.3 688.3 822.9 926.9 980.5 5531.4 5911.3 6006.5 6079.4 6113.2 471.7 478.7 487.1 498.5 627.1 3852.9 3979.3 3988.7 4014.6 4058.2 10426.9 10730.2 10945.5 10954.9 10992.2 10904.0 10910.0 10911.8 10916.6 10937.7 6868.7 8296.4 8300.5 8351.2 8384.1 5841.2 5872.6 5876.9 5884.2 5921.6 10019.4 10094.1 10116.6 10162.7 10166.1 9847.4 10008.3 10284.0 10363.9 10370.9 6959.5 7004.3 7082.4 7094.9 7110.8 7482.3 7918.9 8292.8 8401.4 8402.7 7590.8 7609.0 7616.4 7719.7 7743.4 7341.5 7437.7 7508.1 7558.8 7569.4 7019.4 7025.7 7129.3 7139.2 7151.4 5056.5 5060.2 5075.2 5144.9 5145.0 4730.5 5030.9 5061.9 5204.3 5228.6 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4479.64 Median:5056.05 stddev:1373.03 (27.2%) stddev-sans-first:1340.42 22:11:08 INFO - PROCESS | 1665 | Values: 291.3 301.4 306.5 308.4 309.2 4243.7 5059.6 5285.7 5311.6 5326.8 3954.0 4561.8 4957.6 5045.8 5257.6 3557.9 4006.6 4031.9 4038.8 4056.0 3625.3 3892.1 4075.0 4106.9 4126.8 3799.7 3875.0 4045.9 4053.3 4060.0 3360.6 3987.1 3988.6 4001.6 4006.7 3102.4 3401.4 3489.3 3538.1 3539.2 4970.3 5184.8 5194.8 5199.9 5207.8 4086.5 4497.1 4540.0 4588.4 4608.4 5066.2 5254.0 5429.2 5504.3 5514.7 3842.8 3857.6 3866.7 3877.5 3880.9 5222.7 5277.7 5489.8 5670.3 5775.9 3303.1 3321.8 3718.7 3837.8 3911.6 3925.0 3971.2 3983.7 3988.0 4002.8 3932.4 4277.9 4436.6 4583.4 4596.5 5529.8 5532.3 5536.9 5544.5 5640.4 1224.2 1339.9 1365.6 1374.0 1374.2 671.5 766.0 834.9 837.8 847.5 4529.9 5037.7 5057.7 5072.4 5083.4 5587.4 5594.0 5616.5 5687.3 5729.3 4611.7 4824.7 4839.7 4853.8 5122.6 2996.5 3034.3 3043.0 3046.9 3067.8 4857.4 5142.6 5817.8 5920.3 6005.7 5054.4 5680.5 5851.9 5855.5 5882.6 5584.9 5906.3 5907.6 5927.9 5956.4 5392.8 5711.0 5759.0 5777.6 5796.0 4793.5 5351.5 5653.4 5654.2 5663.4 5002.6 5382.9 5726.2 5841.1 5889.9 3531.4 3775.6 3789.1 3792.5 3802.7 4433.1 5756.3 5757.5 5779.9 5804.9 5179.7 5516.8 5546.3 5597.4 5629.5 4992.1 5097.5 5293.9 5436.9 5573.7 4376.9 4796.1 5115.7 5480.1 5492.1 5261.5 5493.6 5496.2 5539.6 5562.7 4950.4 5059.8 5403.3 5448.4 5483.6 22:11:08 INFO - PROCESS | 1665 | 22:11:08 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:24620.15 Median:3649.64 stddev:38876.53 (1065.2%) stddev-sans-first:38943.73 22:11:08 INFO - PROCESS | 1665 | Values: 594.8 690.6 702.4 770.8 840.4 14866.9 16195.6 16244.0 16246.0 16273.8 12543.0 14789.3 14886.6 14966.4 14998.9 702.0 723.6 726.6 728.5 891.9 721.2 723.7 894.6 921.2 924.2 964.2 969.4 970.3 971.9 974.0 843.2 938.0 948.2 963.7 969.5 557.5 704.5 722.5 723.4 728.3 15435.8 15467.3 15471.4 15512.7 15514.3 14140.5 14578.6 14616.5 14662.0 14676.4 13120.9 13206.5 13245.3 13411.9 13813.6 4675.5 4691.8 4715.7 4890.8 4929.2 111660.0 117128.0 117809.0 117856.4 118180.0 11540.8 11711.6 11735.1 11791.1 11877.2 23632.3 23656.9 23678.2 23687.8 23696.8 7891.9 8141.1 8150.0 8181.7 8451.2 4528.5 4583.9 4630.7 4708.6 4801.1 3512.0 3642.1 3642.3 3648.9 3650.3 607.9 666.7 668.1 672.6 696.6 617.3 622.4 645.1 647.6 652.5 7545.3 8792.0 9099.6 9117.5 9138.0 526.7 557.7 564.7 581.3 583.5 3570.7 4205.1 4400.9 4439.3 4499.4 121471.0 140437.0 143961.3 147185.0 150273.0 80159.0 120043.4 134141.0 134332.0 138590.0 102824.0 116394.4 117268.4 118539.4 120081.0 8609.7 8643.9 8790.4 8798.8 8889.3 42806.0 53426.5 63465.0 63471.0 66007.3 74906.3 76494.6 87183.0 91256.0 93988.0 13242.7 13585.0 13612.4 13705.1 14170.4 14814.3 14862.3 15075.9 15211.0 15596.1 11205.4 13860.5 13888.8 13997.7 14219.9 13887.5 13982.2 14184.1 14201.5 14555.1 13501.9 13851.4 13872.5 13883.0 14211.7 9297.5 11075.6 11302.7 11537.3 11655.1 8097.8 9040.5 10309.4 11223.5 11256.9 22:11:08 INFO - PROCESS | 1665 | -------- Summary: end -------- 22:11:08 INFO - PROCESS | 1665 | 22:11:09 INFO - TEST-INFO | 1665: exit 0 22:11:09 INFO - TEST-OK | dromaeo_css | took 1116007ms 22:11:09 INFO - TEST-START | kraken 22:11:09 INFO - operating with platform_type : mac_ 22:11:09 INFO - Initialising browser for kraken test... 22:11:09 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp1znFG8/profile http://localhost:49271/getInfo.html) 22:11:11 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 22:11:11 INFO - PROCESS | 1672 | colorDepth:24 22:11:11 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 22:11:11 INFO - PROCESS | 1672 | __metrics 22:11:11 INFO - PROCESS | 1672 | 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" 22:11:11 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:11:12 INFO - TEST-INFO | 1672: exit 0 22:11:12 INFO - Browser initialized. 22:11:12 INFO - Running cycle 1/1 for kraken test... 22:11:12 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp1znFG8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:24 INFO - PROCESS | 1673 | RSS: Main: 243757056 22:11:24 INFO - PROCESS | 1673 | 22:11:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 22:11:28 INFO - PROCESS | 1673 | RSS: Main: 294395904 22:11:28 INFO - PROCESS | 1673 | 22:11:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 22:11:31 INFO - PROCESS | 1673 | RSS: Main: 304578560 22:11:31 INFO - PROCESS | 1673 | 22:11:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 22:11:35 INFO - PROCESS | 1673 | RSS: Main: 387989504 22:11:35 INFO - PROCESS | 1673 | 22:11:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 22:11:38 INFO - PROCESS | 1673 | RSS: Main: 290009088 22:11:38 INFO - PROCESS | 1673 | 22:11:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:11:41 INFO - PROCESS | 1673 | RSS: Main: 293974016 22:11:41 INFO - PROCESS | 1673 | 22:11:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 22:11:44 INFO - PROCESS | 1673 | RSS: Main: 330858496 22:11:44 INFO - PROCESS | 1673 | 22:11:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 22:11:47 INFO - PROCESS | 1673 | RSS: Main: 333099008 22:11:47 INFO - PROCESS | 1673 | 22:11:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 22:11:51 INFO - PROCESS | 1673 | RSS: Main: 331464704 22:11:51 INFO - PROCESS | 1673 | 22:11:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:11:53 INFO - PROCESS | 1673 | RSS: Main: 311779328 22:11:53 INFO - PROCESS | 1673 | 22:11:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:11:56 INFO - PROCESS | 1673 | RSS: Main: 319283200 22:11:56 INFO - PROCESS | 1673 | 22:11:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:11:59 INFO - PROCESS | 1673 | RSS: Main: 318091264 22:11:59 INFO - PROCESS | 1673 | 22:12:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:12:02 INFO - PROCESS | 1673 | RSS: Main: 324624384 22:12:02 INFO - PROCESS | 1673 | 22:12:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:12:06 INFO - PROCESS | 1673 | RSS: Main: 327249920 22:12:06 INFO - PROCESS | 1673 | 22:12:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 22:12:08 INFO - PROCESS | 1673 | RSS: Main: 371200000 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | __start_tp_report 22:12:08 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 22:12:08 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 22:12:08 INFO - PROCESS | 1673 | |i|pagename|runs| 22:12:08 INFO - PROCESS | 1673 | |0;ai-astar;101;111;112;112;112;112;111;112;113;110 22:12:08 INFO - PROCESS | 1673 | |1;audio-beat-detection;142;109;108;162;105;113;159;134;141;106 22:12:08 INFO - PROCESS | 1673 | |2;audio-dft;182;184;183;185;183;185;182;186;181;182 22:12:08 INFO - PROCESS | 1673 | |3;audio-fft;78;74;59;125;81;82;68;63;84;57 22:12:08 INFO - PROCESS | 1673 | |4;audio-oscillator;85;83;93;70;70;68;98;70;67;97 22:12:08 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;85;89;85;87;85;88;85;87;84 22:12:08 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;93;93;94;92;93;93;93;93;92 22:12:08 INFO - PROCESS | 1673 | |7;imaging-desaturate;80;87;88;86;84;85;84;100;141;140 22:12:08 INFO - PROCESS | 1673 | |8;json-parse-financial;55;53;53;55;55;55;54;54;54;53 22:12:08 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;64;76;76;70;70;69;69;69;70 22:12:08 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;86;86;83;84;83;82;84;82;87;84 22:12:08 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;139;137;137;139;136;135;142;148;220;146 22:12:08 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;165;164;167;172;225;198;163;164;180;224 22:12:08 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;67;67;66;67;66;68;65;65;67 22:12:08 INFO - PROCESS | 1673 | __end_tp_report 22:12:08 INFO - PROCESS | 1673 | __start_cc_report 22:12:08 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2024 22:12:08 INFO - PROCESS | 1673 | __end_cc_report 22:12:08 INFO - PROCESS | 1673 | __startTimestamp1468041128205__endTimestamp 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | ------- Summary: start ------- 22:12:08 INFO - PROCESS | 1673 | Number of tests: 14 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.47 (3.1%) stddev-sans-first:0.87 22:12:08 INFO - PROCESS | 1673 | Values: 101.0 111.0 112.0 112.0 112.0 112.0 111.0 112.0 113.0 110.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:137.50 stddev:22.40 (16.3%) stddev-sans-first:23.17 22:12:08 INFO - PROCESS | 1673 | Values: 142.0 109.0 108.0 162.0 105.0 113.0 159.0 134.0 141.0 106.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.64 (0.9%) stddev-sans-first:1.67 22:12:08 INFO - PROCESS | 1673 | Values: 182.0 184.0 183.0 185.0 183.0 185.0 182.0 186.0 181.0 182.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:77.10 Median:76.00 stddev:19.45 (25.6%) stddev-sans-first:20.63 22:12:08 INFO - PROCESS | 1673 | Values: 78.0 74.0 59.0 125.0 81.0 82.0 68.0 63.0 84.0 57.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:84.00 stddev:12.60 (15.0%) stddev-sans-first:13.24 22:12:08 INFO - PROCESS | 1673 | Values: 85.0 83.0 93.0 70.0 70.0 68.0 98.0 70.0 67.0 97.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.62 (1.9%) stddev-sans-first:1.69 22:12:08 INFO - PROCESS | 1673 | Values: 87.0 85.0 89.0 85.0 87.0 85.0 88.0 85.0 87.0 84.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:12:08 INFO - PROCESS | 1673 | Values: 93.0 93.0 93.0 94.0 92.0 93.0 93.0 93.0 93.0 92.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:84.50 stddev:23.25 (27.5%) stddev-sans-first:23.78 22:12:08 INFO - PROCESS | 1673 | Values: 80.0 87.0 88.0 86.0 84.0 85.0 84.0 100.0 141.0 140.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.87 22:12:08 INFO - PROCESS | 1673 | Values: 55.0 53.0 53.0 55.0 55.0 55.0 54.0 54.0 54.0 53.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.88 (5.5%) stddev-sans-first:3.71 22:12:08 INFO - PROCESS | 1673 | Values: 65.0 64.0 76.0 76.0 70.0 70.0 69.0 69.0 69.0 70.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.73 (2.1%) stddev-sans-first:1.69 22:12:08 INFO - PROCESS | 1673 | Values: 86.0 86.0 83.0 84.0 83.0 82.0 84.0 82.0 87.0 84.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:140.50 stddev:25.69 (18.3%) stddev-sans-first:27.05 22:12:08 INFO - PROCESS | 1673 | Values: 139.0 137.0 137.0 139.0 136.0 135.0 142.0 148.0 220.0 146.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:176.00 stddev:24.71 (14.0%) stddev-sans-first:25.41 22:12:08 INFO - PROCESS | 1673 | Values: 165.0 164.0 167.0 172.0 225.0 198.0 163.0 164.0 180.0 224.0 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 22:12:08 INFO - PROCESS | 1673 | Values: 66.0 67.0 67.0 66.0 67.0 66.0 68.0 65.0 65.0 67.0 22:12:08 INFO - PROCESS | 1673 | -------- Summary: end -------- 22:12:08 INFO - PROCESS | 1673 | 22:12:08 INFO - TEST-INFO | 1673: exit 0 22:12:09 INFO - TEST-OK | kraken | took 59587ms 22:12:09 INFO - SUITE-END | took 1175s 22:12:09 INFO - Completed test suite (00:19:35) 22:12:09 INFO - javascript benchmark 22:12:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3349.78226415283, 3362.8822991195298, 3366.7306615470766, 3389.593248810143, 3418.0, 5583.553315734742, 5645.407232240617, 5673.8297851064535, 5733.5126514246285, 5755.395683453235, 5656.547476201915, 5672.404397538261, 5808.448197421249, 5816.680082595455, 5848.298204215489, 5105.923411148836, 5125.0, 5201.661891977028, 5215.191645294985, 5312.389075256337, 5296.735163241842, 5329.094054010828, 5334.91997620036, 5345.224942383352, 5376.193570964348, 5140.897182056376, 5141.82003629873, 5188.533032027098, 5201.323827902386, 5217.399999000107, 5158.974205128988, 5163.5352818246165, 5173.275741396207, 5250.422453530108, 5327.280817089682, 5150.021495915764, 5191.169412893919, 5192.7403629818355, 5265.578753699695, 5272.209168624699, 4649.814007439699, 5624.578156638268, 5738.770449182028, 5819.388964158745, 5820.44705752957, 6221.786751583443, 6228.377162283736, 6249.343818899041, 6257.906131408033, 6272.717727702265, 6245.594036387621, 6259.405356491172, 6262.248530176363, 6269.937300626981, 6281.246250449975, 5636.070048442043, 5699.430056994351, 5715.657060576295, 5793.942060579382, 5868.972929737279, 6231.034189700604, 6238.1890354254565, 6287.622742635456, 6288.685565721696, 6297.338779428185, 6119.9902016167725, 6126.6017708848785, 6128.938710612926, 6143.648397352576, 6150.938490615036, 6110.7555697771595, 6139.140520327158, 6178.938210617836, 6274.592151510228, 6276.372362763687, 6153.630782153085, 6206.348333425016, 6266.091416744638, 6272.1219029335925, 6273.341299163522, 6276.686165691697, 6290.276618188876, 6297.811065668037, 6299.716512756937, 6313.747450102036, 4830.3479030331155, 4838.637102217277, 4923.532264434873, 4924.493425871063, 4963.404391472975, 4396.230659634552, 4464.531224221475, 4482.910341793146, 4487.955120448753, 4493.707908985971, 5722.942770572325, 5728.627639203439, 5732.885342293131, 5735.8566035849435, 5745.023346031185, 6159.414855588712, 6216.968915155486, 6223.595466294676, 6227.408396202353, 6232.719527621268, 5766.279215098113, 5774.740136693859, 5804.245448091806, 5805.419458054245, 5814.273215848019, 5454.563634909197, 5459.972700136554, 5475.09660905954, 5477.0, 5478.534324582375, 6148.047052706837, 6221.595596286227, 6233.844153896189, 6276.686165691788, 6324.272708638381, 6255.40573645503, 6262.874742505215, 6280.623162610258, 6282.937170628418, 6295.55931084838, 6163.8150855474405, 6339.080833279057, 6343.682815859281, 6344.175257216532, 6351.9047214290895, 5045.621578381562, 6143.416375444325, 6146.815595532141, 6248.125262463168, 6250.468710159687, 6277.5605707600025, 6285.874282514415, 6287.528435367457, 6290.433860952537, 6299.149614801943, 5968.015277479168, 6053.9091913622215, 6181.196444462191, 6189.721462534287, 6192.535559833121, 4820.060088282891, 6076.118962750465, 6096.993995990611, 6124.6325220486915, 6146.231721034871, 5920.437558431942, 5973.432523910222, 5993.041113421831, 6012.218411606463, 6060.878782424239, 5981.923253814355, 6119.380693981147, 6135.14108024868, 6149.538784591084, 6165.982612868826, 4837.2260438329695, 4956.677815941952, 5947.04847224442, 5957.821265362046, 5998.70006499682], "value": 5690.460356691612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.4551702093656, 526.0714838310381, 529.2061907139291, 542.7150745858423, 551.9448055194483, 30385.08844734656, 35280.294394112105, 36155.0, 36216.45675314873, 38235.42646860292, 44902.775486122526, 45149.7742511287, 45386.63840084794, 45415.54584454154, 45477.54522454774, 8795.824083518326, 9745.658901938452, 9888.505574721272, 9905.30662853597, 9914.157296629795, 8813.779655508632, 9048.095190480932, 9050.1854833065, 9066.954665226665, 11801.232919860182, 11948.70128246792, 12200.023998080133, 12213.206141600813, 12223.755524889497, 12233.388330583479, 12057.638270851889, 12120.212186207957, 12135.08986825987, 12139.15025948184, 12143.0, 8753.518556479392, 11659.883401165964, 11905.642830715005, 11916.642500724993, 11987.0, 42851.57148428507, 43101.35347969783, 43168.78415607902, 43211.919702007384, 43305.78347108276, 3439.4496880499, 3502.544669193014, 3522.2427178156818, 3685.4103343465185, 3784.3566593679143, 25362.365940851625, 25773.35566610831, 32432.83783581067, 32685.50971735426, 33488.99533014013, 3245.107595411257, 3394.2702319001287, 3412.607550131742, 3416.350893330284, 3431.3823511767873, 456901.71549142373, 457619.0, 462277.0, 462942.0, 470260.64869675774, 60523.69738151281, 61335.38664613386, 61362.38637613656, 61381.0, 61411.69294153501, 64366.678166608865, 64669.0, 64672.67663661652, 64844.0, 64890.67554662291, 14233.928830355782, 16530.92548984312, 16531.752023719655, 16532.0, 16585.087820169767, 12849.229046257255, 12886.80669789954, 12912.0, 12913.289769062792, 12975.610731678065, 14245.715085698228, 16094.436694715863, 16141.515754527383, 16174.838251617568, 16242.51272461828, 6337.271213810379, 7304.561726296439, 7330.230325815819, 7367.447441441914, 7418.183999760022, 957.4686049242598, 980.4460479828874, 1065.174489770434, 1088.6679562733443, 1125.763589646167, 29305.85347073251, 29826.0, 30727.38545229083, 32466.35067298641, 32731.0, 959.8464245720651, 1082.7130810335268, 1090.9018188363086, 1158.959436419738, 1198.083466148397, 15190.316435760416, 15277.465288714842, 15280.541583752505, 15345.668738071134, 15410.383584656713, 626884.0, 632160.0, 632442.0, 632483.0, 632553.0, 42825.92935176583, 50181.74909125431, 53730.46269537333, 55846.441535584934, 57045.0, 1222.8104643780227, 1415.6319356967056, 1438.1663379191614, 1445.2773613193403, 1448.2613866927943, 26315.0, 29893.402131957675, 30402.9921822297, 32007.519887202176, 32477.350452990337, 55654.72172639111, 56363.71818140883, 57020.714896425256, 57164.1425378628, 57196.71401642965, 624097.0, 630079.0, 630256.0, 631018.0, 631254.0, 24733.639649819434, 25880.352991175372, 26260.868695656398, 26310.73689263083, 26347.73652263453, 47747.761261194864, 48758.26862597135, 48766.26850597315, 48792.512074880215, 48797.780055497475, 39749.0, 40027.39958900677, 40131.39802903017, 40361.59638403696, 41116.588834112474, 39809.20381592294, 40142.59857401506, 40154.799226003684, 40750.0, 40904.38643420291, 38414.615853842224, 38492.80753596214, 38737.0, 38926.610733892296, 38960.83117506479, 29513.409731805674, 34552.65447345595, 35380.64619353774, 35725.64274357223, 36012.63987360093, 25331.74668253294, 25477.0, 25627.462352258925, 25882.31764935273, 26690.332741681614], "value": 20483.475055683346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.8657396763198, 386.36057325126916, 390.8885967499265, 392.39179272128194, 416.16974136597474, 13220.801687974688, 15414.229288535571, 19953.60092798145, 21725.45686357842, 22088.779112208875, 20785.792142078575, 21192.364229073115, 21618.243361482353, 21862.344129676134, 21912.013959371827, 5946.137810017555, 6124.5100391968535, 6162.1681073055015, 6163.845903852417, 6167.660778657172, 5539.695316757577, 5803.767849286023, 5954.642721436706, 5962.896864080138, 5975.014122669754, 5803.419658034205, 5885.823425297248, 5914.4381283777975, 5925.762969481216, 5928.05151175812, 5741.081426971686, 5874.853128671774, 5901.321348044987, 5916.497097746654, 5919.171316015782, 5122.33409656746, 5186.299849520305, 5192.844214673566, 5192.92210616841, 5199.532042116228, 20129.496762580908, 20298.49253768653, 25285.620715689278, 25949.22152335433, 26218.21345359642, 2154.881481518516, 2250.493638931244, 2283.9657405138937, 2334.2647066174272, 2341.9297421077395, 10007.49962501872, 10245.79508409835, 10400.219983501192, 10726.069307524642, 16051.759223611654, 1834.110456428631, 1865.7947625761155, 1870.8035656256034, 1884.2463014794055, 1912.2733361322607, 79311.20688793153, 80061.0, 107179.0, 133196.00205996918, 135725.3213733925, 10506.842397364046, 11223.0, 11498.597549085742, 11674.474648640831, 11923.986461150726, 13739.519116830863, 14342.928285358506, 14523.709525809425, 14531.92734036323, 14546.199959002144, 6383.074454204115, 6531.673416329165, 6650.900236496456, 7430.702771889172, 7874.133845277015, 7520.699172033167, 7590.278923502258, 7960.363170946311, 7983.241592048862, 8619.827603447897, 4354.477462704495, 4531.932021019687, 4589.54104589545, 4600.631949444037, 4622.699524530962, 1897.345415831536, 1926.3257859749256, 1930.0928563575096, 1934.6324198402258, 1954.452753229098, 909.6543313540813, 961.9899105938737, 978.280963491833, 995.7062666513395, 1002.5087707023506, 20383.388498344775, 21154.365369038955, 22117.33647990563, 22147.778522214892, 22525.12118449448, 753.9849203015909, 957.9568919398644, 978.6281213139104, 979.4417182206074, 1000.564754331868, 10859.565617375374, 11303.0, 11368.772624547462, 11733.823992640117, 11839.408029598486, 592544.0, 629744.0, 636831.0, 650255.0, 661077.0, 111399.0, 152921.0, 153168.0, 155230.2238488823, 155460.0, 885.6988623867787, 967.351874244258, 994.4580203788952, 1058.4760543530847, 1060.1201003167353, 19871.503212419804, 20323.79676203219, 20412.59174816525, 20468.283610074173, 20591.588168236853, 43502.782486087366, 56911.71544142253, 57371.0, 57493.71253143708, 59021.70489147527, 82783.17216827755, 82847.5857620708, 83563.16436835554, 84750.0, 85016.0, 7156.785296441115, 7592.696292148473, 8187.877181842158, 8310.750677479686, 8550.957245213734, 12301.87698123043, 12855.742885142432, 13573.796393053915, 13691.863081369058, 13779.931100344435, 11589.884101158881, 11616.535338586362, 11828.76342473163, 11893.583724569598, 11918.463669134737, 9617.182539484222, 11570.537178512765, 11580.710482238012, 11652.650420487398, 11787.587434440096, 11876.216620913401, 11900.40497975115, 12047.879521204675, 12069.75860482803, 12072.33602151855, 7598.734044308423, 7684.4236682250175, 7686.923130768844, 7710.151883292721, 7786.299233068825, 11277.492512837107, 11595.710107247049, 11850.881491184979, 11870.931616154587, 11900.523979041087], "value": 9653.477937413918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1127.5151684775544, 1556.6964441933824, 1583.1688363609103, 1816.536783120304, 1956.0708663384885, 7713.344365728913, 8182.91817081829, 8195.344372450205, 8196.057453392854, 8230.2592766651, 8414.0, 8662.0, 8669.176428239307, 8819.470831750099, 8844.248238899685, 2334.4630734930934, 2401.987990060047, 2403.098837935774, 2404.1585445094165, 2405.482821193443, 2362.456634973957, 2380.547695937766, 2397.1729753235104, 2403.6995375578053, 2405.6632071510003, 2360.598698221298, 2375.714914210297, 2384.9761502384927, 2397.1729753235104, 2397.8441401308946, 2311.0986715181093, 2330.883455827202, 2352.5765362234793, 2360.5986982213067, 2364.456175079741, 1949.6783030799902, 2362.716474023111, 2362.7282862470865, 2362.7637236276237, 2370.111208296889, 8850.557472126367, 8854.203121719076, 8956.373053886231, 8965.910340896573, 8968.968568614628, 5184.300119483859, 5248.396434410015, 5357.598180136505, 5419.729013549346, 5454.53636440903, 3524.788512689247, 3579.49887015818, 3652.36083685354, 3652.5799533053773, 3659.121810765397, 921.0973246218743, 944.8866136063648, 951.5908159491416, 955.9808803823885, 959.2326139088701, 11570.421478926104, 11732.882671173349, 11741.647750567497, 11749.29504229749, 11772.823407648977, 4440.800163992628, 4485.147821913826, 4658.67389282747, 4666.2067448533835, 4675.719456832601, 9228.7692807679, 9237.214836738818, 9250.4912229828, 9253.629854805733, 9259.351845370895, 5281.207818827206, 5282.128448805981, 5290.867728306746, 5295.338082739658, 5305.814296499604, 4447.288433850568, 4590.609798167193, 4770.6660533763, 4920.384951881015, 4960.950390496121, 3758.981205093957, 3775.0373654717873, 3850.9037274067814, 3879.0690234343556, 3888.7083468739957, 1169.602335206017, 1185.6028230542865, 1185.8221266809876, 1198.646399312205, 1198.9460474278678, 650.2619526837074, 688.2738710660301, 822.9300509456643, 926.9165775080276, 980.5195454227377, 5531.419200983919, 5911.3201981772645, 6006.489448396936, 6079.388961925093, 6113.20528331323, 471.73818530715783, 478.6601512925792, 487.1085912779605, 498.4990084964587, 627.1439485102802, 3852.903677408031, 3979.263836190314, 3988.660963818108, 4014.598540145962, 4058.188362327546, 10426.947865260625, 10730.248882578144, 10945.507452164671, 10954.890451095387, 10992.230543862013, 10903.964123408263, 10910.0, 10911.781764364669, 10916.617918372978, 10937.671869843918, 6868.7252509900045, 8296.419250652518, 8300.543470109202, 8351.20663536963, 8384.077751447336, 5841.240638716939, 5872.55955803325, 5876.883392155477, 5884.205632239764, 5921.585489015728, 10019.448930308903, 10094.14199793026, 10116.595336186765, 10162.74593135148, 10166.135878450119, 9847.359921605073, 10008.299419040597, 10284.023017813295, 10363.94818025905, 10370.948145259526, 6959.478039146983, 7004.334588214111, 7082.433405327459, 7094.929050709633, 7110.7511237106455, 7482.251774822692, 7918.881216781868, 8292.751217463485, 8401.369897257608, 8402.731262559197, 7590.8102297443, 7608.961955190189, 7616.352610027988, 7719.729809456642, 7743.380529557735, 7341.522801017917, 7437.70249190048, 7508.061492313461, 7558.773236802905, 7569.3566046886635, 7019.4033507150425, 7025.718971241297, 7129.322714342129, 7139.214686384597, 7151.356377926011, 5056.469070747666, 5060.2156665716875, 5075.187969924795, 5144.922826157685, 5144.9742751286, 4730.455997560324, 5030.874228144326, 5061.898762024665, 5204.297419848272, 5228.58171346299], "value": 4469.664082150342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.33284777858705, 301.40322162119, 306.4820453433697, 308.43556291985664, 309.16371215861096, 4243.724157929732, 5059.59523238141, 5285.709285989274, 5311.601629877765, 5326.786928522859, 3953.9522026662885, 4561.791125351784, 4957.5786058185095, 5045.77294021769, 5257.553107985826, 3557.92884142317, 4006.559278479365, 4031.911383926338, 4038.7576745395295, 4056.0, 3625.329314076891, 3892.143728379766, 4074.9592504075026, 4106.8973275668195, 4126.834926602933, 3799.6580307772297, 3875.0, 4045.858394956177, 4053.3312003519386, 4060.0, 3360.630330663625, 3987.142764305688, 3988.641022307977, 4001.639852413297, 4006.659433948118, 3102.3784901757977, 3401.4047541680357, 3489.342562282921, 3538.0800991742076, 3539.185987222953, 4970.304157417964, 5184.792608295664, 5194.792208311663, 5199.922001169985, 5207.817726379596, 4086.5300490443383, 4497.078098989699, 4539.954600453986, 4588.403507543998, 4608.400907881987, 5066.238899799618, 5254.0, 5429.185622156669, 5504.311961004874, 5514.6691198528215, 3842.8462861485787, 3857.5756666766633, 3866.6906647468136, 3877.507159743519, 3880.8835734927716, 5222.738863056832, 5277.656952298088, 5489.835304940858, 5670.262865827457, 5775.884482310373, 3303.0916497963253, 3321.8276545898607, 3718.721095917817, 3837.808109594565, 3911.6088391160656, 3925.0, 3971.225611005826, 3983.701222408331, 3987.980060099739, 4002.8398864045694, 3932.4018523051086, 4277.946585638911, 4436.600705936481, 4583.427071616048, 4596.47140578831, 5529.834104976857, 5532.2531458253425, 5536.86494268674, 5544.500994910397, 5640.407757185519, 1224.222618637154, 1339.912905661129, 1365.6312795545148, 1373.9725205495834, 1374.216696483015, 671.5366397185926, 765.9774201441027, 834.870595057767, 837.8031162676766, 847.4915050969493, 4529.881234205327, 5037.722925239147, 5057.696538207646, 5072.442031376546, 5083.415407228216, 5587.441255874462, 5593.9861649806035, 5616.522595579421, 5687.317521897398, 5729.255196824386, 4611.654125940501, 4824.710517368969, 4839.68542044773, 4853.781579828917, 5122.590192784568, 2996.4905965985836, 3034.332446861687, 3042.9847850760602, 3046.908592742221, 3067.7852550321263, 4857.417109946829, 5142.631576317518, 5817.778266563984, 5920.348761636216, 6005.6997150142315, 5054.393472783289, 5680.494668912742, 5851.941480585225, 5855.473007429352, 5882.558738123631, 5584.944150558524, 5906.26171728534, 5907.645541267538, 5927.911081333869, 5956.434138756811, 5392.784288628411, 5711.0, 5759.034097439906, 5777.566682498746, 5796.0293500208445, 4793.520647935178, 5351.464853514617, 5653.378128405954, 5654.208410822571, 5663.3770285267765, 5002.624803139706, 5382.865428364322, 5726.226959360433, 5841.101641966293, 5889.8770860398745, 3531.399662057405, 3775.6413140751583, 3789.090618251655, 3792.4690543323404, 3802.695784337191, 4433.091216300715, 5756.30924289088, 5757.5393968481585, 5779.884402312015, 5804.939246567408, 5179.741012949413, 5516.793282686797, 5546.278983732089, 5597.44025597437, 5629.521490673174, 4992.101421744121, 5097.464766199644, 5293.894122117613, 5436.918446223231, 5573.693446860461, 4376.868693939187, 4796.064767370469, 5115.74421278942, 5480.095784195563, 5492.121260598445, 5261.500157485033, 5493.587980901369, 5496.1755736639825, 5539.556835453073, 5562.72186390687, 4950.356453661001, 5059.772310245974, 5403.27134796925, 5448.392724146307, 5483.616146869681], "value": 4035.982119704262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.8192837218122, 690.5890994858057, 702.3748863511474, 770.7957391291309, 840.4453060979755, 14866.925665371671, 16195.595110122253, 16244.0, 16246.02523848567, 16273.837261627395, 12543.0, 14789.260536973163, 14886.553403397887, 14966.401343946256, 14998.850011499882, 701.9964900175491, 723.634564544904, 726.5531698005715, 728.50097683087, 891.8974317953438, 721.163450397539, 723.7249845058897, 894.592960203106, 921.1847514949251, 924.1728652855691, 964.1756298364867, 969.4474149734654, 970.3207754571828, 971.8639390485338, 973.9951300243524, 843.2284459719349, 937.9541810880887, 948.1750876737249, 963.6530848894428, 969.5443141723379, 557.4843269975256, 704.4962851561107, 722.4725950056436, 723.3815088099651, 728.2717282717283, 15435.845641543554, 15467.303971321318, 15471.381144754196, 15512.689746205013, 15514.301856416489, 14140.505082322172, 14578.562643120724, 14616.488422905148, 14662.0, 14676.412943482248, 13120.86879131206, 13206.537771177962, 13245.337733113305, 13411.865881341257, 13813.585592432242, 4675.532446755331, 4691.7888695008805, 4715.740634265114, 4890.8043678252825, 4929.1620424527555, 111659.99999999919, 117128.0, 117809.0, 117856.41071794672, 118180.0, 11540.826887596693, 11711.648650540497, 11735.061195104372, 11791.056715462742, 11877.22798018126, 23632.29103126909, 23656.881715591313, 23678.171264005676, 23687.81560921947, 23696.7630323698, 7891.881621775678, 8141.104478507359, 8150.021997360354, 8181.713640022571, 8451.197136272045, 4528.47922488918, 4583.862484125481, 4630.652701047367, 4708.576228139415, 4801.087793319328, 3511.964880351215, 3642.1440961374055, 3642.2897534980425, 3648.945265821015, 3650.3429382710838, 607.9483243924316, 666.6633350158236, 668.0913957018452, 672.6266921858415, 696.6238231355012, 617.3394467919284, 622.3993845938601, 645.1129696667083, 647.6049609738056, 652.4962926347009, 7545.320921117126, 8792.032876383593, 9099.590518426687, 9117.498537580497, 9137.986891619215, 526.6997811247629, 557.7349817720703, 564.6922427277061, 581.3091318919186, 583.4952765857503, 3570.6607872252093, 4205.111941407937, 4400.889977750582, 4439.311906654474, 4499.4150760400935, 121471.0, 140437.0, 143961.28019359836, 147185.0, 150273.0, 80159.0, 120043.39978300052, 134141.0, 134332.0, 138589.999999998, 102824.0, 116394.41802791102, 117268.41365793116, 118539.40730296294, 120080.99999999825, 8609.65561377538, 8643.91356086431, 8790.42862213954, 8798.824023519623, 8889.288856891304, 42806.0, 53426.46573534215, 63465.0, 63471.0, 66007.33992660012, 74906.25093748992, 76494.61752691424, 87183.0, 91256.0, 93988.0, 13242.735145297233, 13584.981126415756, 13612.38744256491, 13705.109167904055, 14170.362333694802, 14814.333354998835, 14862.256887155816, 15075.924620376827, 15211.0, 15596.14221217844, 11205.383703896365, 13860.514881979083, 13888.791668124784, 13997.650058748206, 14219.928900355431, 13887.513937012156, 13982.23097729594, 14184.148951062969, 14201.502947396792, 14555.05392149507, 13501.864981350454, 13851.37668804886, 13872.514461993782, 13883.0, 14211.715765684836, 9297.535123243946, 11075.612353567587, 11302.660920172408, 11537.307761534335, 11655.125865560287, 8097.8380432392205, 9040.502772347583, 10309.360231920988, 11223.494942727433, 11256.887431125911], "value": 7876.323776334048, "unit": "score"}], "name": "dromaeo_css", "value": 7367.24658742489, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0, 113.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 108.0, 162.0, 105.0, 113.0, 159.0, 134.0, 141.0, 106.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 185.0, 183.0, 185.0, 182.0, 186.0, 181.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 59.0, 125.0, 81.0, 82.0, 68.0, 63.0, 84.0, 57.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 93.0, 70.0, 70.0, 68.0, 98.0, 70.0, 67.0, 97.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 85.0, 87.0, 85.0, 88.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 88.0, 86.0, 84.0, 85.0, 84.0, 100.0, 141.0, 140.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 55.0, 55.0, 55.0, 54.0, 54.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 76.0, 76.0, 70.0, 70.0, 69.0, 69.0, 69.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 84.0, 83.0, 82.0, 84.0, 82.0, 87.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 137.0, 139.0, 136.0, 135.0, 142.0, 148.0, 220.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 167.0, 172.0, 225.0, 198.0, 163.0, 164.0, 180.0, 224.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 67.0, 66.0, 68.0, 65.0, 65.0, 67.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1460.1000000000001, "alertThreshold": 2.0}]} 22:12:09 INFO - Return code: 0 22:12:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:12:09 INFO - # TBPL SUCCESS # 22:12:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:12:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:12:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:12:09 INFO - 2016-07-08 22:12:09.537 screenresolution[1677:10778] starting screenresolution argv=screenresolution get 22:12:09 INFO - 2016-07-08 22:12:09.539 screenresolution[1677:10778] Display 0: 1600x1200x32@60 22:12:09 INFO - 2016-07-08 22:12:09.545 screenresolution[1678:10781] starting screenresolution argv=screenresolution list 22:12:09 INFO - Available Modes on Display 0 22:12:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:12:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:12:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:12:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:12:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:12:09 INFO - Intel Iris: 22:12:09 INFO - Chipset Model: Intel Iris 22:12:09 INFO - Type: GPU 22:12:09 INFO - Bus: Built-In 22:12:09 INFO - VRAM (Dynamic, Max): 1536 MB 22:12:09 INFO - Vendor: Intel (0x8086) 22:12:09 INFO - Device ID: 0x0a2e 22:12:09 INFO - Revision ID: 0x0009 22:12:09 INFO - Displays: 22:12:09 INFO - Display: 22:12:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:12:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:12:09 INFO - Display Serial Number: 1600x1200 60 22:12:09 INFO - Main Display: Yes 22:12:09 INFO - Mirror: Off 22:12:09 INFO - Online: Yes 22:12:09 INFO - Rotation: Supported 22:12:09 INFO - Return code: 0 22:12:09 INFO - Running post-action listener: _resource_record_post_action 22:12:09 INFO - ##### 22:12:09 INFO - ##### Finished run-tests step (success) 22:12:09 INFO - ##### 22:12:09 INFO - Running post-run listener: _resource_record_post_run 22:12:09 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31768064; Write bytes: 706378752; Read time: 198; Write time: 2017 22:12:09 INFO - TinderboxPrint: CPU usage
53.5% 22:12:09 INFO - TinderboxPrint: I/O read bytes / time
31,768,064 / 198 22:12:09 INFO - TinderboxPrint: I/O write bytes / time
706,378,752 / 2,017 22:12:09 INFO - TinderboxPrint: CPU idle
2,250.1 (46.5%) 22:12:09 INFO - TinderboxPrint: CPU system
967.9 (20.0%) 22:12:09 INFO - TinderboxPrint: CPU user
1,623.7 (33.5%) 22:12:09 INFO - TinderboxPrint: Swap in / out
435,384,320 / 0 22:12:09 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402885120; Write bytes: 395585024; Read time: 29684; Write time: 852 22:12:09 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29703680; Write bytes: 296089088; Read time: 180; Write time: 1125 22:12:10 INFO - Running post-run listener: _upload_blobber_files 22:12:10 INFO - Blob upload gear active. 22:12:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:12:10 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 22:12:10 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:12:10 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:12:10 INFO - (blobuploader) - INFO - Open directory for files ... 22:12:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:12:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:12:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:12:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:12:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:12:11 INFO - (blobuploader) - INFO - Done attempting. 22:12:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:12:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:12:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:12:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:12:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:12:12 INFO - (blobuploader) - INFO - Done attempting. 22:12:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:12:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:12:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:12:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:12:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:12:15 INFO - (blobuploader) - INFO - Done attempting. 22:12:15 INFO - (blobuploader) - INFO - Iteration through files over. 22:12:15 INFO - Return code: 0 22:12:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:12:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:12:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55bd18959cc44f14cfd1493637a2cfc5052d0da172245a0ba7723f9e8261e110e52ab1a1f21aab98c24ed8f670c3b7a98bd3cee3fc798f987efa4848be8f91d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a074093ed72f73d0ab811b756c64cd04c7888b841fa87b38c05cbeb240d693dcf0614fd555707cd8549badadacf145dbf0802726dee9906b84dfcf1aa5f75159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a72e29815b28a83943eff6cc748cd05ff8a4cf819a23e3de1f820fc20cb3ba271c87a05fa1dcced662213cace77801af35eba60fe5e80c873317e90fde60cce2"} 22:12:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:12:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:12:15 INFO - Contents: 22:12:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55bd18959cc44f14cfd1493637a2cfc5052d0da172245a0ba7723f9e8261e110e52ab1a1f21aab98c24ed8f670c3b7a98bd3cee3fc798f987efa4848be8f91d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a074093ed72f73d0ab811b756c64cd04c7888b841fa87b38c05cbeb240d693dcf0614fd555707cd8549badadacf145dbf0802726dee9906b84dfcf1aa5f75159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a72e29815b28a83943eff6cc748cd05ff8a4cf819a23e3de1f820fc20cb3ba271c87a05fa1dcced662213cace77801af35eba60fe5e80c873317e90fde60cce2"} 22:12:15 INFO - Running post-run listener: copy_logs_to_upload_dir 22:12:15 INFO - Copying logs to upload dir... 22:12:15 INFO - mkdir: /builds/slave/test/build/upload/logs 22:12:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.902796 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-08 22:12:15.362849) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 22:12:15.367597) ========= 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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/Fx-Team/sha512/e55bd18959cc44f14cfd1493637a2cfc5052d0da172245a0ba7723f9e8261e110e52ab1a1f21aab98c24ed8f670c3b7a98bd3cee3fc798f987efa4848be8f91d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a074093ed72f73d0ab811b756c64cd04c7888b841fa87b38c05cbeb240d693dcf0614fd555707cd8549badadacf145dbf0802726dee9906b84dfcf1aa5f75159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a72e29815b28a83943eff6cc748cd05ff8a4cf819a23e3de1f820fc20cb3ba271c87a05fa1dcced662213cace77801af35eba60fe5e80c873317e90fde60cce2"} build_url:https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'https://queue.taskcluster.net/v1/task/MEzjbmcrSAiHMTEAYk9GZQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e55bd18959cc44f14cfd1493637a2cfc5052d0da172245a0ba7723f9e8261e110e52ab1a1f21aab98c24ed8f670c3b7a98bd3cee3fc798f987efa4848be8f91d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a074093ed72f73d0ab811b756c64cd04c7888b841fa87b38c05cbeb240d693dcf0614fd555707cd8549badadacf145dbf0802726dee9906b84dfcf1aa5f75159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a72e29815b28a83943eff6cc748cd05ff8a4cf819a23e3de1f820fc20cb3ba271c87a05fa1dcced662213cace77801af35eba60fe5e80c873317e90fde60cce2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 22:12:15.401980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 22:12:15.402307) ========= 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.ffOMNz8etR/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.QEO6ZQSnM6/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005129 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 22:12:15.454294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 22:12:15.454957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 22:12:15.455644) ========= ========= Total master_lag: 0.18 =========