builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1467990598.91 results: success (0) buildid: 20160708054226 builduid: 1dd033011bb044168b3a51cf9f51f333 revision: c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:58.910084) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:58.915093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:58.915375) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:58.953952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:58.954198) ========= 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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:09:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.6M=0.001s 2016-07-08 08:09:59 (13.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.528610 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:59.514016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:59.514311) ========= 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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.025565 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:09:59.563520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 08:09:59.563886) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 --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 c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:09:59,645 truncating revision to first 12 chars 2016-07-08 08:09:59,645 Setting DEBUG logging. 2016-07-08 08:09:59,645 attempt 1/10 2016-07-08 08:09:59,645 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c1e24c4b05ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 08:10:01,040 unpacking tar archive at: fx-team-c1e24c4b05ba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.680603 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 08:10:01.267257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 08:10:01.267608) ========= script_repo_revision: c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 08:10:01.268088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 08:10:01.268362) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 08:10:01.287155) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-07-08 08:10:01.287535) ========= /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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:01 INFO - MultiFileLogger online at 20160708 08:10:01 in /builds/slave/test 08:10:01 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 08:10:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:10:01 INFO - {'append_to_log': False, 08:10:01 INFO - 'base_work_dir': '/builds/slave/test', 08:10:01 INFO - 'blob_upload_branch': 'Fx-Team', 08:10:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:10:01 INFO - 'branch': 'Fx-Team', 08:10:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:01 INFO - 'config_files': ('talos/mac_config.py',), 08:10:01 INFO - 'default_actions': ('clobber', 08:10:01 INFO - 'read-buildbot-config', 08:10:01 INFO - 'download-and-extract', 08:10:01 INFO - 'populate-webroot', 08:10:01 INFO - 'create-virtualenv', 08:10:01 INFO - 'install', 08:10:01 INFO - 'run-tests'), 08:10:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:01 INFO - 'download_minidump_stackwalk': True, 08:10:01 INFO - 'download_symbols': 'ondemand', 08:10:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:01 INFO - 'installer_path': 'installer.exe', 08:10:01 INFO - 'log_level': 'info', 08:10:01 INFO - 'log_name': 'talos', 08:10:01 INFO - 'log_to_console': True, 08:10:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:10:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:10:01 INFO - 'opt_config_files': (), 08:10:01 INFO - 'pip_index': False, 08:10:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:10:01 INFO - 'cmd': ('bash', 08:10:01 INFO - '-c', 08:10:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:10:01 INFO - 'enabled': True, 08:10:01 INFO - 'halt_on_failure': False, 08:10:01 INFO - 'name': 'check_screen_resolution'},), 08:10:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:10:01 INFO - 'cmd': ('bash', 08:10:01 INFO - '-c', 08:10:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:10:01 INFO - 'enabled': True, 08:10:01 INFO - 'halt_on_failure': False, 08:10:01 INFO - 'name': 'check_screen_resolution'},), 08:10:01 INFO - 'run_cmd_checks_enabled': True, 08:10:01 INFO - 'sps_profile': False, 08:10:01 INFO - 'sps_profile_interval': 0, 08:10:01 INFO - 'suite': 'dromaeojs', 08:10:01 INFO - 'system_bits': '32', 08:10:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:01 INFO - 'title': 't-yosemite-r7-0249', 08:10:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:01 INFO - 'use_talos_json': True, 08:10:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:10:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:01 INFO - 'work_dir': 'build'} 08:10:01 INFO - ##### 08:10:01 INFO - ##### Running clobber step. 08:10:01 INFO - ##### 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: clobber 08:10:01 INFO - rmtree: /builds/slave/test/build 08:10:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:10:03 INFO - Running post-action listener: _resource_record_post_action 08:10:03 INFO - ##### 08:10:03 INFO - ##### Finished clobber step (success) 08:10:03 INFO - ##### 08:10:03 INFO - ##### 08:10:03 INFO - ##### Running read-buildbot-config step. 08:10:03 INFO - ##### 08:10:03 INFO - Running pre-action listener: _resource_record_pre_action 08:10:03 INFO - Running main action method: read_buildbot_config 08:10:03 INFO - Using buildbot properties: 08:10:03 INFO - { 08:10:03 INFO - "project": "", 08:10:03 INFO - "product": "firefox", 08:10:03 INFO - "script_repo_revision": "production", 08:10:03 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:10:03 INFO - "repository": "", 08:10:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:10:03 INFO - "buildid": "20160708054226", 08:10:03 INFO - "pgo_build": "False", 08:10:03 INFO - "basedir": "/builds/slave/test", 08:10:03 INFO - "buildnumber": 77, 08:10:03 INFO - "slavename": "t-yosemite-r7-0249", 08:10:03 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:10:03 INFO - "platform": "yosemite_r7", 08:10:03 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:10:03 INFO - "branch": "fx-team", 08:10:03 INFO - "repo_path": "integration/fx-team", 08:10:03 INFO - "revision": "c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5", 08:10:03 INFO - "stage_platform": "macosx64", 08:10:03 INFO - "builduid": "1dd033011bb044168b3a51cf9f51f333", 08:10:03 INFO - "slavebuilddir": "test" 08:10:03 INFO - } 08:10:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 08:10:03 INFO - Running post-action listener: _resource_record_post_action 08:10:03 INFO - ##### 08:10:03 INFO - ##### Finished read-buildbot-config step (success) 08:10:03 INFO - ##### 08:10:03 INFO - ##### 08:10:03 INFO - ##### Running download-and-extract step. 08:10:03 INFO - ##### 08:10:03 INFO - Running pre-action listener: _resource_record_pre_action 08:10:03 INFO - Running main action method: download_and_extract 08:10:03 INFO - mkdir: /builds/slave/test/build/tests 08:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:03 INFO - https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:10:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:10:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:10:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:05 INFO - Downloaded 1199 bytes. 08:10:05 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 08:10:05 INFO - Using the following test package requirements: 08:10:05 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:10:05 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 08:10:05 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 08:10:05 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'jsshell-mac.zip'], 08:10:05 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 08:10:05 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:10:05 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 08:10:05 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 08:10:05 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 08:10:05 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:10:05 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 08:10:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:05 INFO - https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:10:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:10:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:10:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:06 INFO - Downloaded 18806446 bytes. 08:10:06 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'] 08:10:06 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 08:10:10 INFO - Return code: 0 08:10:10 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 08:10: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')]} 08:10:10 INFO - https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:10:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:10:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:10:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:22 INFO - Downloaded 18806446 bytes. 08:10:22 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'] 08:10:22 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 08:10:26 INFO - Return code: 0 08:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:26 INFO - https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:27 INFO - Downloaded 14403333 bytes. 08:10:27 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'] 08:10:27 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 08:10:27 INFO - Return code: 0 08:10:27 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')]} 08:10:27 INFO - https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:10:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:10:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:10:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 08:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NvKW3vkpRXG848-O_wNOzg/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 08:10:32 INFO - Downloaded 112474073 bytes. 08:10:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:10:32 INFO - mkdir: /builds/slave/test/properties 08:10:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:32 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:32 INFO - Contents: 08:10:32 INFO - build_url:https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:10:33 INFO - Running post-action listener: _resource_record_post_action 08:10:33 INFO - Running post-action listener: set_extra_try_arguments 08:10:33 INFO - ##### 08:10:33 INFO - ##### Finished download-and-extract step (success) 08:10:33 INFO - ##### 08:10:33 INFO - ##### 08:10:33 INFO - ##### Running populate-webroot step. 08:10:33 INFO - ##### 08:10:33 INFO - Running pre-action listener: _resource_record_pre_action 08:10:33 INFO - Running main action method: populate_webroot 08:10:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:33 INFO - '--tppagecycles', 08:10:33 INFO - '7'], 08:10:33 INFO - 'tests': ['tsvgm']}}, 08:10:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['damp', 'tps']}, 08:10:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'tests': ['damp', 'tps']}, 08:10:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['dromaeo_dom']}, 08:10:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['basic_compositor_video']}, 08:10:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:10:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['a11yr', 08:10:33 INFO - 'ts_paint', 08:10:33 INFO - 'tpaint', 08:10:33 INFO - 'sessionrestore', 08:10:33 INFO - 'sessionrestore_no_auto_restore', 08:10:33 INFO - 'tabpaint']}, 08:10:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['tsvgx', 08:10:33 INFO - 'tsvgr_opacity', 08:10:33 INFO - 'tart', 08:10:33 INFO - 'tscrollx', 08:10:33 INFO - 'cart']}, 08:10:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:33 INFO - 'tsvgr_opacity', 08:10:33 INFO - 'tart', 08:10:33 INFO - 'tscrollx', 08:10:33 INFO - 'cart']}, 08:10:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'talos_options': ['--disable-e10s'], 08:10:33 INFO - 'tests': ['tp5o']}, 08:10:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'tests': ['tp5o']}, 08:10:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'talos_options': ['--disable-e10s', 08:10:33 INFO - '--xperf_path', 08:10:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:33 INFO - 'tests': ['tp5n']}, 08:10:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:33 INFO - 'talos_options': ['--xperf_path', 08:10:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:33 INFO - 'tests': ['tp5n']}}, 08:10:33 INFO - 'talos.zip': {'path': '', 08:10:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:33 INFO - Running post-action listener: _resource_record_post_action 08:10:33 INFO - ##### 08:10:33 INFO - ##### Finished populate-webroot step (success) 08:10:33 INFO - ##### 08:10:33 INFO - ##### 08:10:33 INFO - ##### Running create-virtualenv step. 08:10:33 INFO - ##### 08:10:33 INFO - Running pre-action listener: _resource_record_pre_action 08:10:33 INFO - Running main action method: create_virtualenv 08:10:33 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:33 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 08:10:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:33 INFO - Using real prefix '/tools/python27' 08:10:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:34 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:37 INFO - Installing pip.................done. 08:10:37 INFO - Return code: 0 08:10:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:37 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:37 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 08:10:37 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 08:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:37 INFO - 'HOME': '/Users/cltbld', 08:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:37 INFO - 'LOGNAME': 'cltbld', 08:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:37 INFO - 'MOZ_NO_REMOTE': '1', 08:10:37 INFO - 'NO_EM_RESTART': '1', 08:10:37 INFO - 'PAGER': '/bin/cat', 08:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:37 INFO - 'PWD': '/builds/slave/test', 08:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:37 INFO - 'SHELL': '/bin/bash', 08:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:37 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:37 INFO - 'USER': 'cltbld', 08:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:37 INFO - 'XPC_FLAGS': '0x0', 08:10:37 INFO - 'XPC_SERVICE_NAME': '0', 08:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:37 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 08:10:37 INFO - Cleaning up... 08:10:37 INFO - Return code: 0 08:10:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:37 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:37 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 08:10:37 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 08:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:37 INFO - 'HOME': '/Users/cltbld', 08:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:37 INFO - 'LOGNAME': 'cltbld', 08:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:37 INFO - 'MOZ_NO_REMOTE': '1', 08:10:37 INFO - 'NO_EM_RESTART': '1', 08:10:37 INFO - 'PAGER': '/bin/cat', 08:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:37 INFO - 'PWD': '/builds/slave/test', 08:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:37 INFO - 'SHELL': '/bin/bash', 08:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:37 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:37 INFO - 'USER': 'cltbld', 08:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:37 INFO - 'XPC_FLAGS': '0x0', 08:10:37 INFO - 'XPC_SERVICE_NAME': '0', 08:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:37 INFO - Downloading/unpacking psutil>=3.1.1 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:41 INFO - Installing collected packages: psutil 08:10:41 INFO - Running setup.py install for psutil 08:10:41 INFO - building 'psutil._psutil_osx' extension 08:10:41 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 08:10:41 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 08:10:41 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 08:10:41 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 08:10:42 INFO - building 'psutil._psutil_posix' extension 08:10:42 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 08:10:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:10:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:10:42 INFO - ^ 08:10:42 INFO - 1 warning generated. 08:10:42 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 08:10:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:42 INFO - Successfully installed psutil 08:10:42 INFO - Cleaning up... 08:10:42 INFO - Return code: 0 08:10:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:42 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')]} 08:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:42 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')]} 08:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:42 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:42 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 08:10:42 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 08:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:42 INFO - 'HOME': '/Users/cltbld', 08:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:42 INFO - 'LOGNAME': 'cltbld', 08:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:42 INFO - 'MOZ_NO_REMOTE': '1', 08:10:42 INFO - 'NO_EM_RESTART': '1', 08:10:42 INFO - 'PAGER': '/bin/cat', 08:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:42 INFO - 'PWD': '/builds/slave/test', 08:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:42 INFO - 'SHELL': '/bin/bash', 08:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:42 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:42 INFO - 'USER': 'cltbld', 08:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:42 INFO - 'XPC_FLAGS': '0x0', 08:10:42 INFO - 'XPC_SERVICE_NAME': '0', 08:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:45 INFO - Installing collected packages: mozsystemmonitor 08:10:45 INFO - Running setup.py install for mozsystemmonitor 08:10:45 INFO - Successfully installed mozsystemmonitor 08:10:45 INFO - Cleaning up... 08:10:45 INFO - Return code: 0 08:10:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:45 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:45 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 08:10:45 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 08:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:45 INFO - 'HOME': '/Users/cltbld', 08:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:45 INFO - 'LOGNAME': 'cltbld', 08:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:45 INFO - 'MOZ_NO_REMOTE': '1', 08:10:45 INFO - 'NO_EM_RESTART': '1', 08:10:45 INFO - 'PAGER': '/bin/cat', 08:10:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:45 INFO - 'PWD': '/builds/slave/test', 08:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:45 INFO - 'SHELL': '/bin/bash', 08:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:45 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:45 INFO - 'USER': 'cltbld', 08:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:45 INFO - 'XPC_FLAGS': '0x0', 08:10:45 INFO - 'XPC_SERVICE_NAME': '0', 08:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:45 INFO - Downloading/unpacking jsonschema==2.5.1 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 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 08:10:48 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:48 INFO - warning: no files found matching '*.txt' 08:10:48 INFO - no previously-included directories found matching 'build' 08:10:48 INFO - no previously-included directories found matching 'dist' 08:10:48 INFO - no previously-included directories found matching '.git*' 08:10:48 INFO - Installing collected packages: jsonschema, functools32 08:10:48 INFO - Running setup.py install for functools32 08:10:48 INFO - warning: no files found matching '*.txt' 08:10:48 INFO - no previously-included directories found matching 'build' 08:10:48 INFO - no previously-included directories found matching 'dist' 08:10:48 INFO - no previously-included directories found matching '.git*' 08:10:48 INFO - Successfully installed jsonschema functools32 08:10:48 INFO - Cleaning up... 08:10:48 INFO - Return code: 0 08:10:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10: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')]} 08:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10: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')]} 08:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:48 INFO - 'HOME': '/Users/cltbld', 08:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:48 INFO - 'LOGNAME': 'cltbld', 08:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:48 INFO - 'MOZ_NO_REMOTE': '1', 08:10:48 INFO - 'NO_EM_RESTART': '1', 08:10:48 INFO - 'PAGER': '/bin/cat', 08:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:48 INFO - 'PWD': '/builds/slave/test', 08:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:48 INFO - 'SHELL': '/bin/bash', 08:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:48 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:48 INFO - 'USER': 'cltbld', 08:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:48 INFO - 'XPC_FLAGS': '0x0', 08:10:48 INFO - 'XPC_SERVICE_NAME': '0', 08:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:49 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:51 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:51 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 08:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:52 INFO - Downloading docopt-0.6.1.tar.gz 08:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:52 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:52 INFO - Running setup.py install for blobuploader 08:10:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:52 INFO - Running setup.py install for requests 08:10:53 INFO - Running setup.py install for docopt 08:10:53 INFO - Successfully installed blobuploader requests docopt 08:10:53 INFO - Cleaning up... 08:10:53 INFO - Return code: 0 08:10:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:53 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:53 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 08:10:53 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 08:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:53 INFO - 'HOME': '/Users/cltbld', 08:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:53 INFO - 'LOGNAME': 'cltbld', 08:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:53 INFO - 'MOZ_NO_REMOTE': '1', 08:10:53 INFO - 'NO_EM_RESTART': '1', 08:10:53 INFO - 'PAGER': '/bin/cat', 08:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:53 INFO - 'PWD': '/builds/slave/test', 08:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:53 INFO - 'SHELL': '/bin/bash', 08:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:53 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:53 INFO - 'USER': 'cltbld', 08:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:53 INFO - 'XPC_FLAGS': '0x0', 08:10:53 INFO - 'XPC_SERVICE_NAME': '0', 08:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:53 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MkRk51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:53 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-XPJ1nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vTxoH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-V3m6qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-zdzwqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-iau3YY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UzoKlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-7XqYle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xvJ8sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-izcgpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:54 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-kRwGGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-NMizfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cXEy7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-zVoG8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-703Td3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-36Zq36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:55 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-YVslab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:55 INFO - Running setup.py install for manifestparser 08:10:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:55 INFO - Running setup.py install for mozcrash 08:10:55 INFO - Running setup.py install for mozdebug 08:10:56 INFO - Running setup.py install for mozdevice 08:10:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Running setup.py install for mozfile 08:10:56 INFO - Running setup.py install for mozhttpd 08:10:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Running setup.py install for mozinfo 08:10:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Running setup.py install for mozInstall 08:10:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:56 INFO - Running setup.py install for mozleak 08:10:56 INFO - Running setup.py install for mozlog 08:10:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Running setup.py install for moznetwork 08:10:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Running setup.py install for mozprocess 08:10:57 INFO - Running setup.py install for mozprofile 08:10:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Running setup.py install for mozrunner 08:10:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:57 INFO - Running setup.py install for mozscreenshot 08:10:57 INFO - Running setup.py install for moztest 08:10:58 INFO - Running setup.py install for mozversion 08:10:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:58 INFO - Cleaning up... 08:10:58 INFO - Return code: 0 08:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10: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')]} 08:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10: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')]} 08:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:58 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:10:58 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 08:10:58 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 08:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:58 INFO - 'HOME': '/Users/cltbld', 08:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:58 INFO - 'LOGNAME': 'cltbld', 08:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:58 INFO - 'MOZ_NO_REMOTE': '1', 08:10:58 INFO - 'NO_EM_RESTART': '1', 08:10:58 INFO - 'PAGER': '/bin/cat', 08:10:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:58 INFO - 'PWD': '/builds/slave/test', 08:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:58 INFO - 'SHELL': '/bin/bash', 08:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:10:58 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:58 INFO - 'USER': 'cltbld', 08:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:58 INFO - 'XPC_FLAGS': '0x0', 08:10:58 INFO - 'XPC_SERVICE_NAME': '0', 08:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-SJf1mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:58 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)) 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-SYhahj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:58 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)) 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-lbezp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:58 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)) 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xz5iNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:58 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)) 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-EMD6AV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:58 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)) 08:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-hCMGaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-7WKbUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UnsZuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-kIfBVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-zm8cy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-uFYhKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-nv9e6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UpQesy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-mfeN68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:59 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)) 08:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:59 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-drNLlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:00 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)) 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:00 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4tb94Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:00 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)) 08:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:00 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-BB2b0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:00 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)) 08:11:00 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)) 08:11:00 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)) 08:11:00 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)) 08:11:00 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)) 08:11:00 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)) 08:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:02 INFO - Downloading blessings-1.6.tar.gz 08:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:02 INFO - Installing collected packages: blessings 08:11:02 INFO - Running setup.py install for blessings 08:11:02 INFO - Successfully installed blessings 08:11:02 INFO - Cleaning up... 08:11:03 INFO - Return code: 0 08:11:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:03 INFO - Reading from file tmpfile_stdout 08:11:03 INFO - Current package versions: 08:11:03 INFO - blessings == 1.6 08:11:03 INFO - blobuploader == 1.2.4 08:11:03 INFO - docopt == 0.6.1 08:11:03 INFO - functools32 == 3.2.3-2 08:11:03 INFO - jsonschema == 2.5.1 08:11:03 INFO - manifestparser == 1.1 08:11:03 INFO - mozInstall == 1.12 08:11:03 INFO - mozcrash == 0.17 08:11:03 INFO - mozdebug == 0.1 08:11:03 INFO - mozdevice == 0.48 08:11:03 INFO - mozfile == 1.2 08:11:03 INFO - mozhttpd == 0.7 08:11:03 INFO - mozinfo == 0.9 08:11:03 INFO - mozleak == 0.1 08:11:03 INFO - mozlog == 3.2 08:11:03 INFO - moznetwork == 0.27 08:11:03 INFO - mozprocess == 0.23 08:11:03 INFO - mozprofile == 0.28 08:11:03 INFO - mozrunner == 6.12 08:11:03 INFO - mozscreenshot == 0.1 08:11:03 INFO - mozsystemmonitor == 0.3 08:11:03 INFO - moztest == 0.7 08:11:03 INFO - mozversion == 1.4 08:11:03 INFO - psutil == 3.1.1 08:11:03 INFO - requests == 1.2.3 08:11:03 INFO - wsgiref == 0.1.2 08:11:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:03 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:11:03 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 08:11:03 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 08:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:03 INFO - 'HOME': '/Users/cltbld', 08:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:03 INFO - 'LOGNAME': 'cltbld', 08:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:03 INFO - 'MOZ_NO_REMOTE': '1', 08:11:03 INFO - 'NO_EM_RESTART': '1', 08:11:03 INFO - 'PAGER': '/bin/cat', 08:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:03 INFO - 'PWD': '/builds/slave/test', 08:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:03 INFO - 'SHELL': '/bin/bash', 08:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:11:03 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:03 INFO - 'USER': 'cltbld', 08:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:03 INFO - 'XPC_FLAGS': '0x0', 08:11:03 INFO - 'XPC_SERVICE_NAME': '0', 08:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 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)) 08:11:03 INFO - Cleaning up... 08:11:03 INFO - Return code: 0 08:11:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:04 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 0x10a33aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3cf618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb97858fd00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, '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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 08:11:04 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 08:11:04 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 08:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:04 INFO - 'HOME': '/Users/cltbld', 08:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:04 INFO - 'LOGNAME': 'cltbld', 08:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:04 INFO - 'MOZ_NO_REMOTE': '1', 08:11:04 INFO - 'NO_EM_RESTART': '1', 08:11:04 INFO - 'PAGER': '/bin/cat', 08:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:04 INFO - 'PWD': '/builds/slave/test', 08:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:04 INFO - 'SHELL': '/bin/bash', 08:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:11:04 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:04 INFO - 'USER': 'cltbld', 08:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:04 INFO - 'XPC_FLAGS': '0x0', 08:11:04 INFO - 'XPC_SERVICE_NAME': '0', 08:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:04 INFO - Cleaning up... 08:11:04 INFO - Return code: 0 08:11:04 INFO - Running post-action listener: _resource_record_post_action 08:11:04 INFO - Running post-action listener: _start_resource_monitoring 08:11:04 INFO - Starting resource monitoring. 08:11:04 INFO - ##### 08:11:04 INFO - ##### Finished create-virtualenv step (success) 08:11:04 INFO - ##### 08:11:04 INFO - ##### 08:11:04 INFO - ##### Running install step. 08:11:04 INFO - ##### 08:11:04 INFO - Running pre-action listener: _resource_record_pre_action 08:11:04 INFO - Running main action method: install 08:11:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:04 INFO - Reading from file tmpfile_stdout 08:11:04 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:04 INFO - Reading from file tmpfile_stdout 08:11:04 INFO - Output received: 08:11:04 INFO - Usage: mozinstall [options] installer 08:11:04 INFO - Options: 08:11:04 INFO - -h, --help show this help message and exit 08:11:04 INFO - -d DEST, --destination=DEST 08:11:04 INFO - Directory to install application into. [default: 08:11:04 INFO - "/builds/slave/test"] 08:11:04 INFO - --app=APP Application being installed. [default: firefox] 08:11:04 INFO - mkdir: /builds/slave/test/build/application 08:11:04 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'] 08:11:04 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 08:11:39 INFO - Reading from file tmpfile_stdout 08:11:39 INFO - Output received: 08:11:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:11:39 INFO - Running post-action listener: _resource_record_post_action 08:11:39 INFO - ##### 08:11:39 INFO - ##### Finished install step (success) 08:11:39 INFO - ##### 08:11:39 INFO - ##### 08:11:39 INFO - ##### Running run-tests step. 08:11:39 INFO - ##### 08:11:39 INFO - Running pre-action listener: _resource_record_pre_action 08:11:39 INFO - Running main action method: run_tests 08:11:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:11:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:11:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:11:39 INFO - 2016-07-08 08:11:39.273 screenresolution[1642:8116] starting screenresolution argv=screenresolution get 08:11:39 INFO - 2016-07-08 08:11:39.276 screenresolution[1642:8116] Display 0: 1600x1200x32@60 08:11:39 INFO - 2016-07-08 08:11:39.282 screenresolution[1643:8118] starting screenresolution argv=screenresolution list 08:11:39 INFO - Available Modes on Display 0 08:11:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:11:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:11:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:11:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:11:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:11:39 INFO - Intel Iris: 08:11:39 INFO - Chipset Model: Intel Iris 08:11:39 INFO - Type: GPU 08:11:39 INFO - Bus: Built-In 08:11:39 INFO - VRAM (Dynamic, Max): 1536 MB 08:11:39 INFO - Vendor: Intel (0x8086) 08:11:39 INFO - Device ID: 0x0a2e 08:11:39 INFO - Revision ID: 0x0009 08:11:39 INFO - Displays: 08:11:39 INFO - Display: 08:11:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:11:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:11:39 INFO - Display Serial Number: 1600x1200 60 08:11:39 INFO - Main Display: Yes 08:11:39 INFO - Mirror: Off 08:11:39 INFO - Online: Yes 08:11:39 INFO - Rotation: Supported 08:11:39 INFO - Return code: 0 08:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:39 INFO - Python 2.7.3 08:11:39 INFO - Return code: 0 08:11:39 INFO - grabbing minidump binary from tooltool 08:11: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')]} 08:11:39 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 0x10a29ed30>, '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 0x10a003db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a296b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:39 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 08:11:39 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 08:11:39 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 08:11:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:39 INFO - Return code: 0 08:11:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:11:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:11:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:39 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/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 08:11:39 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/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --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 08:11:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8RSS1UiFwq/Render', 08:11:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:39 INFO - 'HOME': '/Users/cltbld', 08:11:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:39 INFO - 'LOGNAME': 'cltbld', 08:11:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:39 INFO - 'MOZ_NO_REMOTE': '1', 08:11:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:39 INFO - 'NO_EM_RESTART': '1', 08:11:39 INFO - 'PAGER': '/bin/cat', 08:11:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:39 INFO - 'PWD': '/builds/slave/test', 08:11:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:39 INFO - 'SHELL': '/bin/bash', 08:11:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NGPaRFbWQQ/Listeners', 08:11:39 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 08:11:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:39 INFO - 'USER': 'cltbld', 08:11:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:39 INFO - 'XPC_FLAGS': '0x0', 08:11:39 INFO - 'XPC_SERVICE_NAME': '0', 08:11:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:39 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/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 08:11:39 INFO - mozversion application_buildid: 20160708054226 08:11:39 INFO - mozversion application_changeset: c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 08:11:39 INFO - mozversion application_display_name: Nightly 08:11:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:39 INFO - mozversion application_name: Firefox 08:11:39 INFO - mozversion application_remotingname: firefox 08:11:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:11:39 INFO - mozversion application_vendor: Mozilla 08:11:39 INFO - mozversion application_version: 50.0a1 08:11:39 INFO - mozversion platform_buildid: 20160708054226 08:11:39 INFO - mozversion platform_changeset: c1e24c4b05ba1f4cb3e6cc02b766a913942ac1a5 08:11:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:11:39 INFO - mozversion platform_version: 50.0a1 08:11:39 INFO - using testdate: 1467990699 08:11:39 INFO - actual date: 1467990699 08:11:39 INFO - starting webserver on 'localhost:49271' 08:11:39 INFO - SUITE-START | Running 2 tests 08:11:39 INFO - TEST-START | dromaeo_css 08:11:39 INFO - operating with platform_type : mac_ 08:11:39 INFO - Initialising browser for dromaeo_css test... 08:11:39 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmppqz21V/profile http://localhost:49271/getInfo.html) 08:11:42 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 08:11:42 INFO - PROCESS | 1653 | colorDepth:24 08:11:42 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 08:11:42 INFO - PROCESS | 1653 | __metrics 08:11:42 INFO - PROCESS | 1653 | 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" 08:11:42 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:43 INFO - TEST-INFO | 1653: exit 0 08:11:43 INFO - Browser initialized. 08:11:43 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:43 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmppqz21V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:55 INFO - PROCESS | 1656 | RSS: Main: 243748864 08:11:55 INFO - PROCESS | 1656 | 08:12:46 INFO - PROCESS | 1656 | 1467990766796 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] 08:14:57 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 08:14:58 INFO - PROCESS | 1656 | RSS: Main: 369377280 08:14:58 INFO - PROCESS | 1656 | 08:18:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 08:18:02 INFO - PROCESS | 1656 | RSS: Main: 371097600 08:18:02 INFO - PROCESS | 1656 | 08:21:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 08:21:05 INFO - PROCESS | 1656 | RSS: Main: 372199424 08:21:05 INFO - PROCESS | 1656 | 08:24:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 08:24:08 INFO - PROCESS | 1656 | RSS: Main: 363765760 08:24:08 INFO - PROCESS | 1656 | 08:27:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 08:27:12 INFO - PROCESS | 1656 | RSS: Main: 333565952 08:27:12 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 08:30:15 INFO - PROCESS | 1656 | RSS: Main: 384143360 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | __start_tp_report 08:30:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 08:30:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 08:30:15 INFO - PROCESS | 1656 | |i|pagename|runs| 08:30:15 INFO - PROCESS | 1656 | |0;dojo.html;3352.8994130176097;3373.122988023113;3383.8477268522915;3385.1706331948667;3408.5739282589675;5627.324721033482;5678.744456499458;5759;5781.13283007549;5810.099434587641;5738.856528586777;5750.309962804459;5873.823785286438;5888.793892213763;5907.143464197688;5146.768395422215;5170.586353091744;5245.344331958505;5266.368035835705;5347.893042139155;5339.119045357511;5344.732763361836;5369.785208591652;5385.138377859544;5409.404965453797;5111.897762044776;5160.380754309469;5183.98912212115;5245.1869960155855;5292.126799078147;5215.504527069922;5239.1093514102695;5253.947460525423;5277.3139491866195;5309.46905309466;5178.40448348441;5193.610479214074;5222.76497557607;5275.050490911634;5361.678299302054;5695.800646977377;5756.424357564252;5873.970630146865;5878.823635290948;5882.2353094097925;6140.565599444479;6220.129181914535;6221.502279817604;6225;6281.214848143982;6170.105334726484;6214.347493513161;6247.3127955925265;6258.530610204208;6271.968640156816;5847.970760146257;5914.112883067574;5920.141579470996;5926.1999630049395;5931.347551769345;6186.505079593621;6282.780102696475;6326.4622507087415;6344.270408903035;6346.936530634727;5239.816606418757;6160.691965401712;6222.191115155001;6231.376862313732;6233.407826256498;6120.233815636133;6183.227096612924;6257.78097766576;6258.280297765724;6274.372562743689;6127.250613123855;6152.230971128609;6187.938120618826;6189.195404597373;6256.061590761331;5529.225908372831;6007.30915944672;6155.138280640714;6193.102000209944;6206.689665516707;4902.730349830794;5020.673656212334;5027.421846487628;5036.622253331015;5041.0422019816115;4424.004598965272;4446.599806017474;4447.688661793708;4473.3960915276675;4506.7070640408265;5738.770449181986;5759.8560035999435;5763.582417034416;5786.42135786418;5811.0411782056335;6141.240538582634;6230.470410015199;6313.715882785194;6334.334894836068;6335.303116657164;5736.196932429463;5767.798127065533;5773.39379365169;5782.537397008314;5789.739461724233;5480.479354461319;5487.6981766003255;5492.5605951523785;5493.285872836585;5503.504684578407;6209.130721699056;6231.875362492816;6259.092431597484;6290.779822706183;6336.239651241881;6282.685865706788;6290.150829637941;6290.528210384148;6317.778877739257;6319.6208227506495;6273.059041143865;6295.055741638607;6302.369763023661;6311.968440157953;6348.714307856065;5376.628959615273;6135.385782596045;6165;6195.132681424514;6250.3749625037135;5954.46409823118;6260.906086408799;6301.495880329654;6316.021016742412;6316.62100273985;4444.755538445416;6159.753609855556;6195.4424101831055;6201.472874805692;6210.658413787285;5940.673262970578;6074.331823499323;6112.10835914862;6132;6175.69121543912;6030.5778595497895;6065.484433822998;6079.452849243589;6091.5126789857595;6100.725467354068;6012.549058820518;6105.992511235597;6145.784897528565;6164.630122192682;6175.907361389673;5871.2073870027;5933.02105152645;5961.195238642901;5999.310079340931;6052.636841789506 08:30:15 INFO - PROCESS | 1656 | |1;ext.html;459.3202060949794;521.6400683528368;531.29868573483;533.7144627624222;535.9008583412069;30621.69378306216;42292.78853605736;43400.34899476507;43443.565564344346;43463.13073738523;44020.779896100554;44511.55488445114;44520.10959780803;44582.88542786424;44596.3310550342;8915.331350148732;9769.609215631384;9951.552180151873;9983.950080249624;9992.800143997116;8867.290616750677;12045.457954392074;12255.264684118982;12291.631251062483;12431.378431078456;12204.755904881897;12262;12263.080268979818;12268.141230113895;12283.815742763865;8961.820763584692;12001.219920705125;12203.511859525608;12291.508339666403;12317.876821231763;11859.822102668468;12199.512019519298;12201.45093470796;12201.877981220163;12210.877891221064;41868.371974420406;42250.15499689989;42283;42320.788396057826;42346.36480452765;3701.3522633539023;4173.833046678129;4196.559361267054;4227.640650544708;4386.7367957922625;32967.555126586885;33134.6686533134;33211.83394083038;33292.33415331704;33397.998060058235;3127.37452509499;3233.851814021278;3274.7871388359918;3336.816475093869;3443.741719371032;362846;363746;363945;364237;364624;51342.48657513377;60364.09453858196;60416;60582;60846.69576652088;62316.68841655763;62982;63154.0526892097;63183.05225421622;63204.051939220946;11981.88018119825;14807.333669984877;16533;16534;16538.338466461446;11667.124965627612;11968.281903085843;11973.52105915771;12660.303683297507;12890.615236181337;15894.682106357808;16010.279537420844;16023.519294421187;16028.439004634782;16037.278322475517;4735.290149748468;7178.676959536833;7360.2271761465345;7385.4460915431555;7468.477206595486;880.5245167609547;1010.6816352848829;1014.9137323327452;1083.6748975307376;1091.476091476096;28262.576061359097;30843.537346939815;30967.845160774054;31110.53334199989;31395.686043139733;933.5192375926404;1023;1110.9388983605818;1149.8677652069953;1252.5991682661643;15900.602484937968;16326.83673163277;16343.427980020642;16393.918030410012;16410.507684769476;677004;685512;692816;693131;694248;60852.391476085555;61173.69413152906;61459.69270153621;61479.38520614826;61544;1292.3796577642597;1403.8525954774807;1425.536700572318;1440.488626537564;1458.1688437590471;31678.208044799063;32280.35439291248;32696.673033269362;32827.94199247174;33377.99866004024;50817.745911270205;51671;51733;51832.48167518277;52054.219186711474;527911.3604431953;545811;546598;547770;548549;27873.44253114967;41181.58818411898;41298.79350603228;42057.369139463546;42365.576344236164;48325.758371209326;48367.51632483771;49558;49680.25479617881;49802.50197497979;38074.85775426742;40594;40899.7955010223;41360.793196033825;41757.791211043754;35832.820835895654;40317.79841100776;40738.79630601828;41111.38332925068;41154.9711257221;38414.039649009;38820;38967.805160974014;39534.2093158141;39537.60462395339;28194;32928.188949608004;33317.666823331456;35495.82252088723;35684.821575891954;25397.746022540276;25878.482430350912;26073.95704171812;26133.346666333495;26829.731702682722 08:30:15 INFO - PROCESS | 1656 | |2;jquery.html;363.6581613283512;380.516743735456;390.47481137869556;391.9098607320317;412.614205717654;15614.921925390372;16893.746593801086;19297.13162907669;20984.370468885918;21101.366958991235;18717.906410467964;18857.339993100246;18881.71677424836;18912.959787211697;18927.716084258747;4201.34879093741;5910.202122713443;6084.908726369108;6126.754929802825;6134.8159555213415;5178.4562620924635;6758.324167583251;6761.256261811197;6792.932070679304;6827.624480653587;6424.293327733945;6682.933170668304;6684.598924064548;6686.528963627994;6722.831929201785;6434.742610295582;6661.800145995628;6700.061991321219;6704.229013663442;6714.395704386604;5803.506701930299;5829.766809327622;5834.649921004753;5844.795432159854;5845.386234445365;11339.433028348549;23175.145988320895;25162.874185629138;26534.336641583923;27353.31616709578;2227.6658501224783;2300.562893050315;2358.239671516702;2506.2230708480247;2697.325668582854;8614.741557753277;10313.587456501806;10842.241043126984;14114.36485358151;14467.204303763288;1887.7074053521726;1891.8581106416937;1955.9315423960093;2037.633226019316;2118.9682154767693;92802;133318;134790.32604836914;135296;137150;11096.667099987013;11252.943735281271;11446;11668.499450164916;11868.525258989717;14619.488317908823;14647;14652.706945861022;14677.339519721638;14718.926405367905;8100.934261763213;8713;8781.648734050566;8828.337874659297;8947.3736838422;7059.258777828357;7970.681172753025;8824.02935677075;8954.731358059269;8967.237784788365;4605.332226827091;4621.260598304322;4636.675432719677;4675.275332323496;4694.460137084279;1921.8558608104447;1968.9803101969082;1986.5466149305596;2023.261509549004;2024;844.6368061733514;893.3255392178863;927.0405130689704;947.8341290274175;948.2935213266161;21434.571308574054;21943;21992;22150.22474213395;22206.222782202545;975.3855071305032;995.3729150635198;1000.7398076500017;1014.2545229256491;1045.9058684718411;8653.567321633893;11235.101191904792;11332;11350.48922798476;11395.943020284845;617964;621365;625422;629499;635875;77112;84727;103848.40378990586;142309;145841;997.0876647867266;1003.2626019875386;1021.3106153346462;1025.7435641089728;1026.645807196501;19029.953352565743;19227.711584325967;19340.70988935139;19358.128884199963;19381.61236775285;57678.7116064417;59440.70279648574;59460.40539594548;59824.10263846133;59836.99999999826;79234.06505159724;80022;84242.57878710567;88521.11478885387;89485;8351.95824020876;8372.874406884024;8575.785605359915;8693.39146259781;8736;12988.805167922677;13496.865031349953;13581.388837502138;13803.930980345034;13827.930860345634;11445.198836081394;11497.425128743362;11501.252418592765;11541.82687259675;11724.472398742248;11480.598179063692;11503.252288601214;11585.362805045803;11768.882311176778;11816.940915295712;11694.2983420995;11745;12004.579839705917;12019.278843269432;12047.096467764777;7193.3166349196745;7669.8466030677955;8353.538130826959;8427.199416055464;8486.575671216538;11129.610463633733;11713.88286117128;12001.15991880595;12020.63938081859;12058.974987126192 08:30:15 INFO - PROCESS | 1656 | |3;mootools.html;1393.686420555376;1540.0836502281143;1566.8092249890083;1758.577941294089;1902.6194761047784;8101.3518918486525;8925.821483570324;9186.678466253683;9191.356605037658;9272.119148680882;8901.287896968242;9136.314776391764;9149.405288656233;9149.95425022874;9156.404833685805;2379.7263314718857;2410.240774156135;2415.420299128214;2416.1905761569897;2417.576924038295;2365.6333268343346;2389.9880500597474;2402.675638788767;2405.8556486610773;2406.2179791567723;2142.810740039278;2229.58752630763;2384.7496012918655;2399.076355603088;2402.699662542182;2292.1748170658548;2314.8032417244553;2318.8492747971413;2320.7447180810095;2336.077249486443;1952.5021119614548;2351.3063646224405;2353.1293421434007;2355.8822058896985;2356.198892376583;9009.234215091725;9125.81748365029;9184.311176661777;9227.538623068887;9313.115254050854;5379.596530260207;5526.253955715967;5529.336479622471;5531.917021244684;5535.750891209906;3692.538432695913;3702.444633305026;3770.7549009314307;3777.9244415111543;3780.8298626561595;984.9212063035012;1081.2107161771871;1096.8628921384827;1109.1459576126417;1126.2791813239533;12010.69973250667;12083.395830208454;12097.93951030248;12107.697307567381;12110.212836165709;4455.6212721918355;4472.508024117345;4681.414823147106;4691.014886873795;4702.294655801588;9340.851604131089;9346.813063738824;9359.110884465965;9363;9378.109079637423;5297.947020529822;5300.12547929595;5302.1781623848365;5303.893922121536;5310.070737620978;4406.228909940812;4567.040921406476;4778.713277203379;4965.155923493015;4974.204127339608;3496.755227134127;3658.926821463609;3819.388897776342;3908.3746600543777;3941.467901833273;1164.5923926625612;1176.6881776329108;1180.3979970215253;1191.184038933316;1192.5170306026073;648.8377905523619;781.1212386065677;793.8174219929333;836.6527890925198;983.8179936711588;5920.467157955804;6033.7586496540525;6091.208142941389;6102.664353460602;6124.6325220486915;924.2605915267759;924.7872989212519;945.432740355781;1001.5893483671659;1003.533356988989;3402.4385976314124;3951.5060617422823;3991.3214753491975;4020.8391664333685;4073.1853629274265;10699.197560183018;11038.006579407891;11176;11191.104711623213;11198.272112312836;10127.240456965757;11140.554377824796;11149;11150.721231969266;11191.104711623213;8076.757697269091;8437.662493500315;8451.704190353308;8535.231829135408;8561.44350617208;6018.578699490994;6028.547858910687;6143.754249830135;6198.318184999556;6344.333844946214;9947;10025.799484010133;10246.23153263523;10422.583096676048;10555.261131720707;10612.097971672494;10684.358938463716;10717.624883129052;10728.141748660244;10730.248882578144;6958.443324533924;8467.925358287259;8496.957515212385;8596.452638524876;8604.526751028754;8552.358573107167;8570.271526920033;8651.437656552303;8655.740327790176;8665.43674661145;7579.62101894892;7741.535507869546;7889.3294070004695;7899.881501777363;7976.042874855055;7479.476436649382;7599.164091949991;7722.227777222458;7755.534667919943;7862.213778622092;7313.122425308997;7315.963420182865;7406.444516661164;7411;7413.777586672409;5048.495150485069;5084.872878178076;5087.542121209109;5150.201718733602;5154;4489.595936365743;5041.9501751847365;5061.2408138778;5066.9746651266505;5162.225666150108 08:30:15 INFO - PROCESS | 1656 | |4;prototype.html;293.41463779759385;310.1950438611803;316.5710462323552;316.7418553878587;319.0444618367985;4747.643926705502;5502.422245664207;5518.172274158878;5537;5575.832725018251;4466.6426685865135;4581.816727330903;4692.4369075710965;5446.2375267462485;5484.546595152287;3847.49982502275;3954.742941708794;4118.723195809293;4153.356229784373;4188.497380314351;3302.5396079920374;4124.525679546846;4150.605692459227;4173.165366926612;4206.516250631171;3587.569491661004;4075.368317910714;4084.060666046811;4116.464859568252;4123.237201117788;3723.9813800931092;3879.941800872975;4125.546189919107;4135.29699951009;4141.772202528859;3565.4117070683305;3586.794121615214;3590.964090359089;3596.1189508570305;3599.4420864766003;5279.313689220415;5310;5329.8934021319355;5341.359036915556;5350.197470379435;4390.670699697535;4632.606228470584;4670.346151538787;4757.310190022462;4765.785539650724;4453.487848897385;5650.773969041234;5658.7170641467765;5661.971690141564;5709.915116127964;3159.8262095584964;3869.3228684980018;3871.618954343087;3879.262940041383;3903.980480097581;4857.53853383928;5181.34197056942;5494.873550922091;5952.017917043682;6029.909551356734;3869.264839680508;3896.3181443247886;3911.6088391160656;3925.9607403925597;3928.9607103929166;3338.432466480704;3954.5056867891512;3954.723169378116;3962.643362097425;3987.6610488108836;3861.9613803861603;4369.344598310216;4652.603908986875;4665.883352916137;4683.578477937002;5447;5780.624259423124;5782.537397008229;5805.274340707411;5850.327212370632;1268.9214167957164;1289.7936330187124;1310.3644732304824;1318.736252749454;1357.3484727330936;745.8843879198732;785.2226295966951;807.7940125268136;854.7521218846465;855.4653341661461;4574.062317224961;4866.513348665105;5120.078385890575;5154.082896998309;5154.433012368637;5672.347680016768;5791;5946.524278057745;5956.098755315275;5964.671943043174;4811.326414302001;4834.879128021757;5084.872878178076;5307.920381194285;5343.210024641789;2939.2357986923125;3041.7718671099756;3073.5389691546;3129.7809153359044;3134.2477805326557;5252.161743389859;5969;6085.36103709113;6112.480439162721;6164.568480206342;5259.15853463444;5822.282426684138;5988.071848863346;6009.399060093956;6060.909086363708;5799.681017544076;5960.58596268512;6059.454649081604;6112.327643959161;6184.721687523982;4851.660383773243;5488.670679759227;5781.624194427349;5918.638713095926;5948.256467941507;4857.975710121426;5815.67373050783;5820.563457740602;5872.3297938788255;5879.2944846618675;6037.366076561898;6108.328083910677;6114.6942652868065;6164.660943648142;6183.443490085914;3742.315957818938;3797.1076796952143;3823.1589050408866;3842.8270727816757;3902.9804850976698;4901.162064225915;5873.942690315785;5938.703064846827;5970.104484327386;5974.761009559569;4622.930656040229;5386.8383948481605;5507.642003269774;5745.741441635053;5807.82576522705;5299.6290259681455;5397.7840886365675;5477.589180811376;5838.620489668158;5843.386444423274;5351.277577526984;5682.516986056232;5704;5707.743151558273;5709.857253568694;4979.377577802775;5274.446183150714;5718.571107167062;5751.971240143773;5763.394843541536;5615.101583746581;5619.325680918152;5625.493705566519;5626.859328516656;5628.8874222514505 08:30:15 INFO - PROCESS | 1656 | |5;yui.html;647.4464332995287;723.645413747264;734.2473964186711;772.0272456704553;798.209772325398;15220.162891040989;16592.33630654774;16609.91695041526;16622.418215362464;16641.167941602933;14804.703905921875;14945.252737363142;15181.77227341588;15368.001079929796;15375.077495350286;884.9911500884989;885.6280362247871;891.2602539891874;891.9420237684562;893.2452077994117;715.5670819154415;730.8684436801374;731.6049333359979;735.9668814903342;737.292199488489;761.3376362564577;918.4030380252823;949.0888746803078;957.9089986451311;958.0276019839868;818.8290744235741;904.1365495951351;933.6918816790477;933.7292185266265;935.4527601353217;548.5227851768987;688.0401839434012;689.1351354050685;689.9379055884995;689.9896501552481;15050.924745376204;15609.375624974988;15611.219439028004;15626.609334766608;15655.686886262325;14823.258837058103;15029.624259393602;15050.247487625684;15325;15413.5375938722;12038.518459261619;12050.216735912138;12089.455974481169;12275.2021118627;12615.684607884785;3926.5326383718043;3962.1679447316096;3978.2839088964265;3986.940195897064;4084;113312;114875;115601.42199289033;116462;118965;11641.76716465666;11747.823782643267;11751.353675547756;12071.517139314505;12171.452284647212;24013.639795403084;24428.145014924394;24468.02127914899;24485.63271550928;24646.383840403774;7977.880331795028;8179.018517777905;8273.751787446387;8363.749087527383;8385.496870187686;4449.510553839075;4514.525974772667;4544.091181763661;4609.285560738091;4676.13491504066;3530.3468858261303;3677.9448308275396;3678.503402040744;3680.282344942764;3687.59436461989;663.9601623902581;675.3506914658941;682.74738346812;692.9861402771917;696.1263614164192;568.7326956330518;596.6241268001131;683.5556888022825;697.3688811625487;698.979030629082;9551.767300470927;9587.664431744855;9745.756356091013;9746.463944482979;9899.901000990041;572.384204186862;645.2966266769245;683.7165160270906;703.802935178151;710.4068103133941;4562.099102187678;4740.786664600101;4795.2737014674685;4798.136335459651;4808.759562021885;128734;131664.68335316525;153889;155619.66570501451;158828.20585897227;102042.97957020483;133134.66865331418;133506;133699;136420.31789840988;105923.35191619962;110924.4453777726;116486;117424;118053.40973295251;8654.061952949243;9240.306976976619;9265.861012084959;9308.255339572683;9525.571349289437;50915.745421274136;58173.12740308814;58299.70850145722;65319;68548;63255.68372158294;63464.36535634584;95124.04875951429;98796;100033.49983250328;11806.940965295118;11917.702057448287;11973.16187866841;12048.578299759467;12569.497220111092;13462.663433414244;13547.32263386807;13616.319184040956;13907.58277251684;14119.576412707635;11219.55121795119;11654.184207105422;12633.30516821547;13039.804402934153;13060.934695326463;12612.936935315265;12648.114631975674;12686.619401417973;12824.358782061046;13087.672808179872;12257.448414821176;12269.631911042683;12317.568885088978;12619.36903154857;12789.296588687712;10548.367097974146;10550.8417373741;10815.51330190159;11090.44547772592;11150.442477876237;7814.33578145864;10113.431464119865;10525.315854469434;10887.292325998786;10906.945465272624 08:30:15 INFO - PROCESS | 1656 | __end_tp_report 08:30:15 INFO - PROCESS | 1656 | __start_cc_report 08:30:15 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1321 08:30:15 INFO - PROCESS | 1656 | __end_cc_report 08:30:15 INFO - PROCESS | 1656 | __startTimestamp1467991815358__endTimestamp 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | ------- Summary: start ------- 08:30:15 INFO - PROCESS | 1656 | Number of tests: 6 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5762.01 Median:5954.46 stddev:634.61 (10.7%) stddev-sans-first:607.76 08:30:15 INFO - PROCESS | 1656 | Values: 3352.9 3373.1 3383.8 3385.2 3408.6 5627.3 5678.7 5759.0 5781.1 5810.1 5738.9 5750.3 5873.8 5888.8 5907.1 5146.8 5170.6 5245.3 5266.4 5347.9 5339.1 5344.7 5369.8 5385.1 5409.4 5111.9 5160.4 5184.0 5245.2 5292.1 5215.5 5239.1 5253.9 5277.3 5309.5 5178.4 5193.6 5222.8 5275.1 5361.7 5695.8 5756.4 5874.0 5878.8 5882.2 6140.6 6220.1 6221.5 6225.0 6281.2 6170.1 6214.3 6247.3 6258.5 6272.0 5848.0 5914.1 5920.1 5926.2 5931.3 6186.5 6282.8 6326.5 6344.3 6346.9 5239.8 6160.7 6222.2 6231.4 6233.4 6120.2 6183.2 6257.8 6258.3 6274.4 6127.3 6152.2 6187.9 6189.2 6256.1 5529.2 6007.3 6155.1 6193.1 6206.7 4902.7 5020.7 5027.4 5036.6 5041.0 4424.0 4446.6 4447.7 4473.4 4506.7 5738.8 5759.9 5763.6 5786.4 5811.0 6141.2 6230.5 6313.7 6334.3 6335.3 5736.2 5767.8 5773.4 5782.5 5789.7 5480.5 5487.7 5492.6 5493.3 5503.5 6209.1 6231.9 6259.1 6290.8 6336.2 6282.7 6290.2 6290.5 6317.8 6319.6 6273.1 6295.1 6302.4 6312.0 6348.7 5376.6 6135.4 6165.0 6195.1 6250.4 5954.5 6260.9 6301.5 6316.0 6316.6 4444.8 6159.8 6195.4 6201.5 6210.7 5940.7 6074.3 6112.1 6132.0 6175.7 6030.6 6065.5 6079.5 6091.5 6100.7 6012.5 6106.0 6145.8 6164.6 6175.9 5871.2 5933.0 5961.2 5999.3 6052.6 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:68221.28 Median:38244.45 stddev:146542.82 (383.2%) stddev-sans-first:146865.60 08:30:15 INFO - PROCESS | 1656 | Values: 459.3 521.6 531.3 533.7 535.9 30621.7 42292.8 43400.3 43443.6 43463.1 44020.8 44511.6 44520.1 44582.9 44596.3 8915.3 9769.6 9951.6 9984.0 9992.8 8867.3 12045.5 12255.3 12291.6 12431.4 12204.8 12262.0 12263.1 12268.1 12283.8 8961.8 12001.2 12203.5 12291.5 12317.9 11859.8 12199.5 12201.5 12201.9 12210.9 41868.4 42250.2 42283.0 42320.8 42346.4 3701.4 4173.8 4196.6 4227.6 4386.7 32967.6 33134.7 33211.8 33292.3 33398.0 3127.4 3233.9 3274.8 3336.8 3443.7 362846.0 363746.0 363945.0 364237.0 364624.0 51342.5 60364.1 60416.0 60582.0 60846.7 62316.7 62982.0 63154.1 63183.1 63204.1 11981.9 14807.3 16533.0 16534.0 16538.3 11667.1 11968.3 11973.5 12660.3 12890.6 15894.7 16010.3 16023.5 16028.4 16037.3 4735.3 7178.7 7360.2 7385.4 7468.5 880.5 1010.7 1014.9 1083.7 1091.5 28262.6 30843.5 30967.8 31110.5 31395.7 933.5 1023.0 1110.9 1149.9 1252.6 15900.6 16326.8 16343.4 16393.9 16410.5 677004.0 685512.0 692816.0 693131.0 694248.0 60852.4 61173.7 61459.7 61479.4 61544.0 1292.4 1403.9 1425.5 1440.5 1458.2 31678.2 32280.4 32696.7 32827.9 33378.0 50817.7 51671.0 51733.0 51832.5 52054.2 527911.4 545811.0 546598.0 547770.0 548549.0 27873.4 41181.6 41298.8 42057.4 42365.6 48325.8 48367.5 49558.0 49680.3 49802.5 38074.9 40594.0 40899.8 41360.8 41757.8 35832.8 40317.8 40738.8 41111.4 41155.0 38414.0 38820.0 38967.8 39534.2 39537.6 28194.0 32928.2 33317.7 35495.8 35684.8 25397.7 25878.5 26074.0 26133.3 26829.7 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:36021.96 Median:12766.69 stddev:104274.92 (816.8%) stddev-sans-first:104533.06 08:30:15 INFO - PROCESS | 1656 | Values: 363.7 380.5 390.5 391.9 412.6 15614.9 16893.7 19297.1 20984.4 21101.4 18717.9 18857.3 18881.7 18913.0 18927.7 4201.3 5910.2 6084.9 6126.8 6134.8 5178.5 6758.3 6761.3 6792.9 6827.6 6424.3 6682.9 6684.6 6686.5 6722.8 6434.7 6661.8 6700.1 6704.2 6714.4 5803.5 5829.8 5834.6 5844.8 5845.4 11339.4 23175.1 25162.9 26534.3 27353.3 2227.7 2300.6 2358.2 2506.2 2697.3 8614.7 10313.6 10842.2 14114.4 14467.2 1887.7 1891.9 1955.9 2037.6 2119.0 92802.0 133318.0 134790.3 135296.0 137150.0 11096.7 11252.9 11446.0 11668.5 11868.5 14619.5 14647.0 14652.7 14677.3 14718.9 8100.9 8713.0 8781.6 8828.3 8947.4 7059.3 7970.7 8824.0 8954.7 8967.2 4605.3 4621.3 4636.7 4675.3 4694.5 1921.9 1969.0 1986.5 2023.3 2024.0 844.6 893.3 927.0 947.8 948.3 21434.6 21943.0 21992.0 22150.2 22206.2 975.4 995.4 1000.7 1014.3 1045.9 8653.6 11235.1 11332.0 11350.5 11395.9 617964.0 621365.0 625422.0 629499.0 635875.0 77112.0 84727.0 103848.4 142309.0 145841.0 997.1 1003.3 1021.3 1025.7 1026.6 19030.0 19227.7 19340.7 19358.1 19381.6 57678.7 59440.7 59460.4 59824.1 59837.0 79234.1 80022.0 84242.6 88521.1 89485.0 8352.0 8372.9 8575.8 8693.4 8736.0 12988.8 13496.9 13581.4 13803.9 13827.9 11445.2 11497.4 11501.3 11541.8 11724.5 11480.6 11503.3 11585.4 11768.9 11816.9 11694.3 11745.0 12004.6 12019.3 12047.1 7193.3 7669.8 8353.5 8427.2 8486.6 11129.6 11713.9 12001.2 12020.6 12059.0 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5858.28 Median:5008.08 stddev:3340.22 (66.7%) stddev-sans-first:3332.73 08:30:15 INFO - PROCESS | 1656 | Values: 1393.7 1540.1 1566.8 1758.6 1902.6 8101.4 8925.8 9186.7 9191.4 9272.1 8901.3 9136.3 9149.4 9150.0 9156.4 2379.7 2410.2 2415.4 2416.2 2417.6 2365.6 2390.0 2402.7 2405.9 2406.2 2142.8 2229.6 2384.7 2399.1 2402.7 2292.2 2314.8 2318.8 2320.7 2336.1 1952.5 2351.3 2353.1 2355.9 2356.2 9009.2 9125.8 9184.3 9227.5 9313.1 5379.6 5526.3 5529.3 5531.9 5535.8 3692.5 3702.4 3770.8 3777.9 3780.8 984.9 1081.2 1096.9 1109.1 1126.3 12010.7 12083.4 12097.9 12107.7 12110.2 4455.6 4472.5 4681.4 4691.0 4702.3 9340.9 9346.8 9359.1 9363.0 9378.1 5297.9 5300.1 5302.2 5303.9 5310.1 4406.2 4567.0 4778.7 4965.2 4974.2 3496.8 3658.9 3819.4 3908.4 3941.5 1164.6 1176.7 1180.4 1191.2 1192.5 648.8 781.1 793.8 836.7 983.8 5920.5 6033.8 6091.2 6102.7 6124.6 924.3 924.8 945.4 1001.6 1003.5 3402.4 3951.5 3991.3 4020.8 4073.2 10699.2 11038.0 11176.0 11191.1 11198.3 10127.2 11140.6 11149.0 11150.7 11191.1 8076.8 8437.7 8451.7 8535.2 8561.4 6018.6 6028.5 6143.8 6198.3 6344.3 9947.0 10025.8 10246.2 10422.6 10555.3 10612.1 10684.4 10717.6 10728.1 10730.2 6958.4 8467.9 8497.0 8596.5 8604.5 8552.4 8570.3 8651.4 8655.7 8665.4 7579.6 7741.5 7889.3 7899.9 7976.0 7479.5 7599.2 7722.2 7755.5 7862.2 7313.1 7316.0 7406.4 7411.0 7413.8 5048.5 5084.9 5087.5 5150.2 5154.0 4489.6 5042.0 5061.2 5067.0 5162.2 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4620.47 Median:5255.66 stddev:1444.30 (27.5%) stddev-sans-first:1411.36 08:30:15 INFO - PROCESS | 1656 | Values: 293.4 310.2 316.6 316.7 319.0 4747.6 5502.4 5518.2 5537.0 5575.8 4466.6 4581.8 4692.4 5446.2 5484.5 3847.5 3954.7 4118.7 4153.4 4188.5 3302.5 4124.5 4150.6 4173.2 4206.5 3587.6 4075.4 4084.1 4116.5 4123.2 3724.0 3879.9 4125.5 4135.3 4141.8 3565.4 3586.8 3591.0 3596.1 3599.4 5279.3 5310.0 5329.9 5341.4 5350.2 4390.7 4632.6 4670.3 4757.3 4765.8 4453.5 5650.8 5658.7 5662.0 5709.9 3159.8 3869.3 3871.6 3879.3 3904.0 4857.5 5181.3 5494.9 5952.0 6029.9 3869.3 3896.3 3911.6 3926.0 3929.0 3338.4 3954.5 3954.7 3962.6 3987.7 3862.0 4369.3 4652.6 4665.9 4683.6 5447.0 5780.6 5782.5 5805.3 5850.3 1268.9 1289.8 1310.4 1318.7 1357.3 745.9 785.2 807.8 854.8 855.5 4574.1 4866.5 5120.1 5154.1 5154.4 5672.3 5791.0 5946.5 5956.1 5964.7 4811.3 4834.9 5084.9 5307.9 5343.2 2939.2 3041.8 3073.5 3129.8 3134.2 5252.2 5969.0 6085.4 6112.5 6164.6 5259.2 5822.3 5988.1 6009.4 6060.9 5799.7 5960.6 6059.5 6112.3 6184.7 4851.7 5488.7 5781.6 5918.6 5948.3 4858.0 5815.7 5820.6 5872.3 5879.3 6037.4 6108.3 6114.7 6164.7 6183.4 3742.3 3797.1 3823.2 3842.8 3903.0 4901.2 5873.9 5938.7 5970.1 5974.8 4622.9 5386.8 5507.6 5745.7 5807.8 5299.6 5397.8 5477.6 5838.6 5843.4 5351.3 5682.5 5704.0 5707.7 5709.9 4979.4 5274.4 5718.6 5752.0 5763.4 5615.1 5619.3 5625.5 5626.9 5628.9 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24811.99 Median:3604.15 stddev:39676.89 (1100.9%) stddev-sans-first:39746.71 08:30:15 INFO - PROCESS | 1656 | Values: 647.4 723.6 734.2 772.0 798.2 15220.2 16592.3 16609.9 16622.4 16641.2 14804.7 14945.3 15181.8 15368.0 15375.1 885.0 885.6 891.3 891.9 893.2 715.6 730.9 731.6 736.0 737.3 761.3 918.4 949.1 957.9 958.0 818.8 904.1 933.7 933.7 935.5 548.5 688.0 689.1 689.9 690.0 15050.9 15609.4 15611.2 15626.6 15655.7 14823.3 15029.6 15050.2 15325.0 15413.5 12038.5 12050.2 12089.5 12275.2 12615.7 3926.5 3962.2 3978.3 3986.9 4084.0 113312.0 114875.0 115601.4 116462.0 118965.0 11641.8 11747.8 11751.4 12071.5 12171.5 24013.6 24428.1 24468.0 24485.6 24646.4 7977.9 8179.0 8273.8 8363.7 8385.5 4449.5 4514.5 4544.1 4609.3 4676.1 3530.3 3677.9 3678.5 3680.3 3687.6 664.0 675.4 682.7 693.0 696.1 568.7 596.6 683.6 697.4 699.0 9551.8 9587.7 9745.8 9746.5 9899.9 572.4 645.3 683.7 703.8 710.4 4562.1 4740.8 4795.3 4798.1 4808.8 128734.0 131664.7 153889.0 155619.7 158828.2 102043.0 133134.7 133506.0 133699.0 136420.3 105923.4 110924.4 116486.0 117424.0 118053.4 8654.1 9240.3 9265.9 9308.3 9525.6 50915.7 58173.1 58299.7 65319.0 68548.0 63255.7 63464.4 95124.0 98796.0 100033.5 11806.9 11917.7 11973.2 12048.6 12569.5 13462.7 13547.3 13616.3 13907.6 14119.6 11219.6 11654.2 12633.3 13039.8 13060.9 12612.9 12648.1 12686.6 12824.4 13087.7 12257.4 12269.6 12317.6 12619.4 12789.3 10548.4 10550.8 10815.5 11090.4 11150.4 7814.3 10113.4 10525.3 10887.3 10906.9 08:30:15 INFO - PROCESS | 1656 | -------- Summary: end -------- 08:30:15 INFO - PROCESS | 1656 | 08:30:15 INFO - TEST-INFO | 1656: exit 0 08:30:15 INFO - TEST-OK | dromaeo_css | took 1116041ms 08:30:15 INFO - TEST-START | kraken 08:30:15 INFO - operating with platform_type : mac_ 08:30:15 INFO - Initialising browser for kraken test... 08:30:15 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmphAhG2f/profile http://localhost:49271/getInfo.html) 08:30:18 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:30:18 INFO - PROCESS | 1663 | colorDepth:24 08:30:18 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:30:18 INFO - PROCESS | 1663 | __metrics 08:30:18 INFO - PROCESS | 1663 | 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" 08:30:18 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:19 INFO - TEST-INFO | 1663: exit 0 08:30:19 INFO - Browser initialized. 08:30:19 INFO - Running cycle 1/1 for kraken test... 08:30:19 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmphAhG2f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:30 INFO - PROCESS | 1664 | RSS: Main: 243421184 08:30:30 INFO - PROCESS | 1664 | 08:30:33 INFO - PROCESS | 1664 | 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) 08:30:34 INFO - PROCESS | 1664 | RSS: Main: 293863424 08:30:34 INFO - PROCESS | 1664 | 08:30:37 INFO - PROCESS | 1664 | 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) 08:30:37 INFO - PROCESS | 1664 | RSS: Main: 305020928 08:30:37 INFO - PROCESS | 1664 | 08:30:41 INFO - PROCESS | 1664 | 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) 08:30:41 INFO - PROCESS | 1664 | RSS: Main: 389193728 08:30:41 INFO - PROCESS | 1664 | 08:30:43 INFO - PROCESS | 1664 | 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) 08:30:44 INFO - PROCESS | 1664 | RSS: Main: 290312192 08:30:44 INFO - PROCESS | 1664 | 08:30:46 INFO - PROCESS | 1664 | 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) 08:30:47 INFO - PROCESS | 1664 | RSS: Main: 294645760 08:30:47 INFO - PROCESS | 1664 | 08:30:49 INFO - PROCESS | 1664 | 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) 08:30:50 INFO - PROCESS | 1664 | RSS: Main: 331882496 08:30:50 INFO - PROCESS | 1664 | 08:30:53 INFO - PROCESS | 1664 | 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) 08:30:54 INFO - PROCESS | 1664 | RSS: Main: 333623296 08:30:54 INFO - PROCESS | 1664 | 08:30:56 INFO - PROCESS | 1664 | 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) 08:30:57 INFO - PROCESS | 1664 | RSS: Main: 331546624 08:30:57 INFO - PROCESS | 1664 | 08:30:59 INFO - PROCESS | 1664 | 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) 08:30:59 INFO - PROCESS | 1664 | RSS: Main: 313372672 08:30:59 INFO - PROCESS | 1664 | 08:31:01 INFO - PROCESS | 1664 | 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) 08:31:02 INFO - PROCESS | 1664 | RSS: Main: 318922752 08:31:02 INFO - PROCESS | 1664 | 08:31:04 INFO - PROCESS | 1664 | 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) 08:31:05 INFO - PROCESS | 1664 | RSS: Main: 317669376 08:31:05 INFO - PROCESS | 1664 | 08:31:07 INFO - PROCESS | 1664 | 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) 08:31:08 INFO - PROCESS | 1664 | RSS: Main: 326635520 08:31:08 INFO - PROCESS | 1664 | 08:31:11 INFO - PROCESS | 1664 | 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) 08:31:12 INFO - PROCESS | 1664 | RSS: Main: 327958528 08:31:12 INFO - PROCESS | 1664 | 08:31:13 INFO - PROCESS | 1664 | 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) 08:31:14 INFO - PROCESS | 1664 | RSS: Main: 371159040 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | __start_tp_report 08:31:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 08:31:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 08:31:14 INFO - PROCESS | 1664 | |i|pagename|runs| 08:31:14 INFO - PROCESS | 1664 | |0;ai-astar;99;109;112;110;110;110;109;110;110;109 08:31:14 INFO - PROCESS | 1664 | |1;audio-beat-detection;143;112;103;153;106;108;137;136;176;107 08:31:14 INFO - PROCESS | 1664 | |2;audio-dft;183;183;185;178;181;183;177;181;182;182 08:31:14 INFO - PROCESS | 1664 | |3;audio-fft;77;73;56;110;57;57;59;65;100;57 08:31:14 INFO - PROCESS | 1664 | |4;audio-oscillator;88;118;126;102;69;68;97;69;67;101 08:31:14 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;86;90;87;88;86;91;87;89;86 08:31:14 INFO - PROCESS | 1664 | |6;imaging-darkroom;95;103;144;96;108;149;118;94;96;95 08:31:14 INFO - PROCESS | 1664 | |7;imaging-desaturate;86;84;83;83;83;83;85;84;82;83 08:31:14 INFO - PROCESS | 1664 | |8;json-parse-financial;55;54;55;53;53;53;54;55;54;53 08:31:14 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;72;69;77;71;71;70;69;71;76 08:31:14 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;86;83;82;85;82;85;85;83;82;84 08:31:14 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;153;149;146;137;144;151;135;133;180;139 08:31:14 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;162;162;163;164;164;166;162;166;161;161 08:31:14 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;68;70;85;87;84;80;65;66;65;65 08:31:14 INFO - PROCESS | 1664 | __end_tp_report 08:31:14 INFO - PROCESS | 1664 | __start_cc_report 08:31:14 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1996 08:31:14 INFO - PROCESS | 1664 | __end_cc_report 08:31:14 INFO - PROCESS | 1664 | __startTimestamp1467991874103__endTimestamp 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | ------- Summary: start ------- 08:31:14 INFO - PROCESS | 1664 | Number of tests: 14 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:3.55 (3.2%) stddev-sans-first:0.93 08:31:14 INFO - PROCESS | 1664 | Values: 99.0 109.0 112.0 110.0 110.0 110.0 109.0 110.0 110.0 109.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:136.50 stddev:24.73 (18.1%) stddev-sans-first:25.64 08:31:14 INFO - PROCESS | 1664 | Values: 143.0 112.0 103.0 153.0 106.0 108.0 137.0 136.0 176.0 107.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:181.50 Median:182.50 stddev:2.42 (1.3%) stddev-sans-first:2.50 08:31:14 INFO - PROCESS | 1664 | Values: 183.0 183.0 185.0 178.0 181.0 183.0 177.0 181.0 182.0 182.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:71.10 Median:58.00 stddev:19.42 (33.5%) stddev-sans-first:20.48 08:31:14 INFO - PROCESS | 1664 | Values: 77.0 73.0 56.0 110.0 57.0 57.0 59.0 65.0 100.0 57.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:68.50 stddev:21.83 (31.9%) stddev-sans-first:23.14 08:31:14 INFO - PROCESS | 1664 | Values: 88.0 118.0 126.0 102.0 69.0 68.0 97.0 69.0 67.0 101.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.00 stddev:1.75 (2.0%) stddev-sans-first:1.86 08:31:14 INFO - PROCESS | 1664 | Values: 88.0 86.0 90.0 87.0 88.0 86.0 91.0 87.0 89.0 86.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:109.80 Median:94.50 stddev:20.79 (22.0%) stddev-sans-first:21.35 08:31:14 INFO - PROCESS | 1664 | Values: 95.0 103.0 144.0 96.0 108.0 149.0 118.0 94.0 96.0 95.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:83.50 stddev:1.17 (1.4%) stddev-sans-first:0.87 08:31:14 INFO - PROCESS | 1664 | Values: 86.0 84.0 83.0 83.0 83.0 83.0 85.0 84.0 82.0 83.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.83 08:31:14 INFO - PROCESS | 1664 | Values: 55.0 54.0 55.0 53.0 53.0 53.0 54.0 55.0 54.0 53.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:3.45 (4.9%) stddev-sans-first:2.86 08:31:14 INFO - PROCESS | 1664 | Values: 65.0 72.0 69.0 77.0 71.0 71.0 70.0 69.0 71.0 76.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:1.49 (1.8%) stddev-sans-first:1.33 08:31:14 INFO - PROCESS | 1664 | Values: 86.0 83.0 82.0 85.0 82.0 85.0 85.0 83.0 82.0 84.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:147.50 stddev:13.57 (9.2%) stddev-sans-first:14.20 08:31:14 INFO - PROCESS | 1664 | Values: 153.0 149.0 146.0 137.0 144.0 151.0 135.0 133.0 180.0 139.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.50 stddev:1.85 (1.1%) stddev-sans-first:1.92 08:31:14 INFO - PROCESS | 1664 | Values: 162.0 162.0 163.0 164.0 164.0 166.0 162.0 166.0 161.0 161.0 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:75.00 stddev:9.32 (12.4%) stddev-sans-first:9.68 08:31:14 INFO - PROCESS | 1664 | Values: 68.0 70.0 85.0 87.0 84.0 80.0 65.0 66.0 65.0 65.0 08:31:14 INFO - PROCESS | 1664 | -------- Summary: end -------- 08:31:14 INFO - PROCESS | 1664 | 08:31:14 INFO - TEST-INFO | 1664: exit 0 08:31:14 INFO - TEST-OK | kraken | took 58975ms 08:31:14 INFO - SUITE-END | took 1175s 08:31:15 INFO - Completed test suite (00:19:35) 08:31:15 INFO - javascript benchmark 08:31:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.8994130176097, 3373.122988023113, 3383.8477268522915, 3385.1706331948667, 3408.5739282589675, 5627.324721033482, 5678.744456499458, 5759.0, 5781.13283007549, 5810.099434587641, 5738.856528586777, 5750.309962804459, 5873.823785286438, 5888.793892213763, 5907.143464197688, 5146.768395422215, 5170.586353091744, 5245.344331958505, 5266.368035835705, 5347.893042139155, 5339.119045357511, 5344.732763361836, 5369.785208591652, 5385.138377859544, 5409.404965453797, 5111.897762044776, 5160.380754309469, 5183.98912212115, 5245.1869960155855, 5292.126799078147, 5215.504527069922, 5239.1093514102695, 5253.947460525423, 5277.3139491866195, 5309.46905309466, 5178.40448348441, 5193.610479214074, 5222.76497557607, 5275.050490911634, 5361.678299302054, 5695.800646977377, 5756.424357564252, 5873.970630146865, 5878.823635290948, 5882.2353094097925, 6140.565599444479, 6220.129181914535, 6221.502279817604, 6225.0, 6281.214848143982, 6170.105334726484, 6214.347493513161, 6247.3127955925265, 6258.530610204208, 6271.968640156816, 5847.970760146257, 5914.112883067574, 5920.141579470996, 5926.1999630049395, 5931.347551769345, 6186.505079593621, 6282.780102696475, 6326.4622507087415, 6344.270408903035, 6346.936530634727, 5239.816606418757, 6160.691965401712, 6222.191115155001, 6231.376862313732, 6233.407826256498, 6120.233815636133, 6183.227096612924, 6257.78097766576, 6258.280297765724, 6274.372562743689, 6127.250613123855, 6152.230971128609, 6187.938120618826, 6189.195404597373, 6256.061590761331, 5529.225908372831, 6007.30915944672, 6155.138280640714, 6193.102000209944, 6206.689665516707, 4902.730349830794, 5020.673656212334, 5027.421846487628, 5036.622253331015, 5041.0422019816115, 4424.004598965272, 4446.599806017474, 4447.688661793708, 4473.3960915276675, 4506.7070640408265, 5738.770449181986, 5759.8560035999435, 5763.582417034416, 5786.42135786418, 5811.0411782056335, 6141.240538582634, 6230.470410015199, 6313.715882785194, 6334.334894836068, 6335.303116657164, 5736.196932429463, 5767.798127065533, 5773.39379365169, 5782.537397008314, 5789.739461724233, 5480.479354461319, 5487.6981766003255, 5492.5605951523785, 5493.285872836585, 5503.504684578407, 6209.130721699056, 6231.875362492816, 6259.092431597484, 6290.779822706183, 6336.239651241881, 6282.685865706788, 6290.150829637941, 6290.528210384148, 6317.778877739257, 6319.6208227506495, 6273.059041143865, 6295.055741638607, 6302.369763023661, 6311.968440157953, 6348.714307856065, 5376.628959615273, 6135.385782596045, 6165.0, 6195.132681424514, 6250.3749625037135, 5954.46409823118, 6260.906086408799, 6301.495880329654, 6316.021016742412, 6316.62100273985, 4444.755538445416, 6159.753609855556, 6195.4424101831055, 6201.472874805692, 6210.658413787285, 5940.673262970578, 6074.331823499323, 6112.10835914862, 6132.0, 6175.69121543912, 6030.5778595497895, 6065.484433822998, 6079.452849243589, 6091.5126789857595, 6100.725467354068, 6012.549058820518, 6105.992511235597, 6145.784897528565, 6164.630122192682, 6175.907361389673, 5871.2073870027, 5933.02105152645, 5961.195238642901, 5999.310079340931, 6052.636841789506], "value": 5722.979725456725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.3202060949794, 521.6400683528368, 531.29868573483, 533.7144627624222, 535.9008583412069, 30621.69378306216, 42292.78853605736, 43400.34899476507, 43443.565564344346, 43463.13073738523, 44020.779896100554, 44511.55488445114, 44520.10959780803, 44582.88542786424, 44596.3310550342, 8915.331350148732, 9769.609215631384, 9951.552180151873, 9983.950080249624, 9992.800143997116, 8867.290616750677, 12045.457954392074, 12255.264684118982, 12291.631251062483, 12431.378431078456, 12204.755904881897, 12262.0, 12263.080268979818, 12268.141230113895, 12283.815742763865, 8961.820763584692, 12001.219920705125, 12203.511859525608, 12291.508339666403, 12317.876821231763, 11859.822102668468, 12199.512019519298, 12201.45093470796, 12201.877981220163, 12210.877891221064, 41868.371974420406, 42250.15499689989, 42283.0, 42320.788396057826, 42346.36480452765, 3701.3522633539023, 4173.833046678129, 4196.559361267054, 4227.640650544708, 4386.7367957922625, 32967.555126586885, 33134.6686533134, 33211.83394083038, 33292.33415331704, 33397.998060058235, 3127.37452509499, 3233.851814021278, 3274.7871388359918, 3336.816475093869, 3443.741719371032, 362846.0, 363746.0, 363945.0, 364237.0, 364624.0, 51342.48657513377, 60364.09453858196, 60416.0, 60582.0, 60846.69576652088, 62316.68841655763, 62982.0, 63154.0526892097, 63183.05225421622, 63204.051939220946, 11981.88018119825, 14807.333669984877, 16533.0, 16534.0, 16538.338466461446, 11667.124965627612, 11968.281903085843, 11973.52105915771, 12660.303683297507, 12890.615236181337, 15894.682106357808, 16010.279537420844, 16023.519294421187, 16028.439004634782, 16037.278322475517, 4735.290149748468, 7178.676959536833, 7360.2271761465345, 7385.4460915431555, 7468.477206595486, 880.5245167609547, 1010.6816352848829, 1014.9137323327452, 1083.6748975307376, 1091.476091476096, 28262.576061359097, 30843.537346939815, 30967.845160774054, 31110.53334199989, 31395.686043139733, 933.5192375926404, 1023.0, 1110.9388983605818, 1149.8677652069953, 1252.5991682661643, 15900.602484937968, 16326.83673163277, 16343.427980020642, 16393.918030410012, 16410.507684769476, 677004.0, 685512.0, 692816.0, 693131.0, 694248.0, 60852.391476085555, 61173.69413152906, 61459.69270153621, 61479.38520614826, 61544.0, 1292.3796577642597, 1403.8525954774807, 1425.536700572318, 1440.488626537564, 1458.1688437590471, 31678.208044799063, 32280.35439291248, 32696.673033269362, 32827.94199247174, 33377.99866004024, 50817.745911270205, 51671.0, 51733.0, 51832.48167518277, 52054.219186711474, 527911.3604431953, 545811.0, 546598.0, 547770.0, 548549.0, 27873.44253114967, 41181.58818411898, 41298.79350603228, 42057.369139463546, 42365.576344236164, 48325.758371209326, 48367.51632483771, 49558.0, 49680.25479617881, 49802.50197497979, 38074.85775426742, 40594.0, 40899.7955010223, 41360.793196033825, 41757.791211043754, 35832.820835895654, 40317.79841100776, 40738.79630601828, 41111.38332925068, 41154.9711257221, 38414.039649009, 38820.0, 38967.805160974014, 39534.2093158141, 39537.60462395339, 28194.0, 32928.188949608004, 33317.666823331456, 35495.82252088723, 35684.821575891954, 25397.746022540276, 25878.482430350912, 26073.95704171812, 26133.346666333495, 26829.731702682722], "value": 20826.558653988013, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.6581613283512, 380.516743735456, 390.47481137869556, 391.9098607320317, 412.614205717654, 15614.921925390372, 16893.746593801086, 19297.13162907669, 20984.370468885918, 21101.366958991235, 18717.906410467964, 18857.339993100246, 18881.71677424836, 18912.959787211697, 18927.716084258747, 4201.34879093741, 5910.202122713443, 6084.908726369108, 6126.754929802825, 6134.8159555213415, 5178.4562620924635, 6758.324167583251, 6761.256261811197, 6792.932070679304, 6827.624480653587, 6424.293327733945, 6682.933170668304, 6684.598924064548, 6686.528963627994, 6722.831929201785, 6434.742610295582, 6661.800145995628, 6700.061991321219, 6704.229013663442, 6714.395704386604, 5803.506701930299, 5829.766809327622, 5834.649921004753, 5844.795432159854, 5845.386234445365, 11339.433028348549, 23175.145988320895, 25162.874185629138, 26534.336641583923, 27353.31616709578, 2227.6658501224783, 2300.562893050315, 2358.239671516702, 2506.2230708480247, 2697.325668582854, 8614.741557753277, 10313.587456501806, 10842.241043126984, 14114.36485358151, 14467.204303763288, 1887.7074053521726, 1891.8581106416937, 1955.9315423960093, 2037.633226019316, 2118.9682154767693, 92802.0, 133318.0, 134790.32604836914, 135296.0, 137150.0, 11096.667099987013, 11252.943735281271, 11446.0, 11668.499450164916, 11868.525258989717, 14619.488317908823, 14647.0, 14652.706945861022, 14677.339519721638, 14718.926405367905, 8100.934261763213, 8713.0, 8781.648734050566, 8828.337874659297, 8947.3736838422, 7059.258777828357, 7970.681172753025, 8824.02935677075, 8954.731358059269, 8967.237784788365, 4605.332226827091, 4621.260598304322, 4636.675432719677, 4675.275332323496, 4694.460137084279, 1921.8558608104447, 1968.9803101969082, 1986.5466149305596, 2023.261509549004, 2024.0, 844.6368061733514, 893.3255392178863, 927.0405130689704, 947.8341290274175, 948.2935213266161, 21434.571308574054, 21943.0, 21992.0, 22150.22474213395, 22206.222782202545, 975.3855071305032, 995.3729150635198, 1000.7398076500017, 1014.2545229256491, 1045.9058684718411, 8653.567321633893, 11235.101191904792, 11332.0, 11350.48922798476, 11395.943020284845, 617964.0, 621365.0, 625422.0, 629499.0, 635875.0, 77112.0, 84727.0, 103848.40378990586, 142309.0, 145841.0, 997.0876647867266, 1003.2626019875386, 1021.3106153346462, 1025.7435641089728, 1026.645807196501, 19029.953352565743, 19227.711584325967, 19340.70988935139, 19358.128884199963, 19381.61236775285, 57678.7116064417, 59440.70279648574, 59460.40539594548, 59824.10263846133, 59836.99999999826, 79234.06505159724, 80022.0, 84242.57878710567, 88521.11478885387, 89485.0, 8351.95824020876, 8372.874406884024, 8575.785605359915, 8693.39146259781, 8736.0, 12988.805167922677, 13496.865031349953, 13581.388837502138, 13803.930980345034, 13827.930860345634, 11445.198836081394, 11497.425128743362, 11501.252418592765, 11541.82687259675, 11724.472398742248, 11480.598179063692, 11503.252288601214, 11585.362805045803, 11768.882311176778, 11816.940915295712, 11694.2983420995, 11745.0, 12004.579839705917, 12019.278843269432, 12047.096467764777, 7193.3166349196745, 7669.8466030677955, 8353.538130826959, 8427.199416055464, 8486.575671216538, 11129.610463633733, 11713.88286117128, 12001.15991880595, 12020.63938081859, 12058.974987126192], "value": 9862.676806271786, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.686420555376, 1540.0836502281143, 1566.8092249890083, 1758.577941294089, 1902.6194761047784, 8101.3518918486525, 8925.821483570324, 9186.678466253683, 9191.356605037658, 9272.119148680882, 8901.287896968242, 9136.314776391764, 9149.405288656233, 9149.95425022874, 9156.404833685805, 2379.7263314718857, 2410.240774156135, 2415.420299128214, 2416.1905761569897, 2417.576924038295, 2365.6333268343346, 2389.9880500597474, 2402.675638788767, 2405.8556486610773, 2406.2179791567723, 2142.810740039278, 2229.58752630763, 2384.7496012918655, 2399.076355603088, 2402.699662542182, 2292.1748170658548, 2314.8032417244553, 2318.8492747971413, 2320.7447180810095, 2336.077249486443, 1952.5021119614548, 2351.3063646224405, 2353.1293421434007, 2355.8822058896985, 2356.198892376583, 9009.234215091725, 9125.81748365029, 9184.311176661777, 9227.538623068887, 9313.115254050854, 5379.596530260207, 5526.253955715967, 5529.336479622471, 5531.917021244684, 5535.750891209906, 3692.538432695913, 3702.444633305026, 3770.7549009314307, 3777.9244415111543, 3780.8298626561595, 984.9212063035012, 1081.2107161771871, 1096.8628921384827, 1109.1459576126417, 1126.2791813239533, 12010.69973250667, 12083.395830208454, 12097.93951030248, 12107.697307567381, 12110.212836165709, 4455.6212721918355, 4472.508024117345, 4681.414823147106, 4691.014886873795, 4702.294655801588, 9340.851604131089, 9346.813063738824, 9359.110884465965, 9363.0, 9378.109079637423, 5297.947020529822, 5300.12547929595, 5302.1781623848365, 5303.893922121536, 5310.070737620978, 4406.228909940812, 4567.040921406476, 4778.713277203379, 4965.155923493015, 4974.204127339608, 3496.755227134127, 3658.926821463609, 3819.388897776342, 3908.3746600543777, 3941.467901833273, 1164.5923926625612, 1176.6881776329108, 1180.3979970215253, 1191.184038933316, 1192.5170306026073, 648.8377905523619, 781.1212386065677, 793.8174219929333, 836.6527890925198, 983.8179936711588, 5920.467157955804, 6033.7586496540525, 6091.208142941389, 6102.664353460602, 6124.6325220486915, 924.2605915267759, 924.7872989212519, 945.432740355781, 1001.5893483671659, 1003.533356988989, 3402.4385976314124, 3951.5060617422823, 3991.3214753491975, 4020.8391664333685, 4073.1853629274265, 10699.197560183018, 11038.006579407891, 11176.0, 11191.104711623213, 11198.272112312836, 10127.240456965757, 11140.554377824796, 11149.0, 11150.721231969266, 11191.104711623213, 8076.757697269091, 8437.662493500315, 8451.704190353308, 8535.231829135408, 8561.44350617208, 6018.578699490994, 6028.547858910687, 6143.754249830135, 6198.318184999556, 6344.333844946214, 9947.0, 10025.799484010133, 10246.23153263523, 10422.583096676048, 10555.261131720707, 10612.097971672494, 10684.358938463716, 10717.624883129052, 10728.141748660244, 10730.248882578144, 6958.443324533924, 8467.925358287259, 8496.957515212385, 8596.452638524876, 8604.526751028754, 8552.358573107167, 8570.271526920033, 8651.437656552303, 8655.740327790176, 8665.43674661145, 7579.62101894892, 7741.535507869546, 7889.3294070004695, 7899.881501777363, 7976.042874855055, 7479.476436649382, 7599.164091949991, 7722.227777222458, 7755.534667919943, 7862.213778622092, 7313.122425308997, 7315.963420182865, 7406.444516661164, 7411.0, 7413.777586672409, 5048.495150485069, 5084.872878178076, 5087.542121209109, 5150.201718733602, 5154.0, 4489.595936365743, 5041.9501751847365, 5061.2408138778, 5066.9746651266505, 5162.225666150108], "value": 4654.444046934251, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.41463779759385, 310.1950438611803, 316.5710462323552, 316.7418553878587, 319.0444618367985, 4747.643926705502, 5502.422245664207, 5518.172274158878, 5537.0, 5575.832725018251, 4466.6426685865135, 4581.816727330903, 4692.4369075710965, 5446.2375267462485, 5484.546595152287, 3847.49982502275, 3954.742941708794, 4118.723195809293, 4153.356229784373, 4188.497380314351, 3302.5396079920374, 4124.525679546846, 4150.605692459227, 4173.165366926612, 4206.516250631171, 3587.569491661004, 4075.368317910714, 4084.060666046811, 4116.464859568252, 4123.237201117788, 3723.9813800931092, 3879.941800872975, 4125.546189919107, 4135.29699951009, 4141.772202528859, 3565.4117070683305, 3586.794121615214, 3590.964090359089, 3596.1189508570305, 3599.4420864766003, 5279.313689220415, 5310.0, 5329.8934021319355, 5341.359036915556, 5350.197470379435, 4390.670699697535, 4632.606228470584, 4670.346151538787, 4757.310190022462, 4765.785539650724, 4453.487848897385, 5650.773969041234, 5658.7170641467765, 5661.971690141564, 5709.915116127964, 3159.8262095584964, 3869.3228684980018, 3871.618954343087, 3879.262940041383, 3903.980480097581, 4857.53853383928, 5181.34197056942, 5494.873550922091, 5952.017917043682, 6029.909551356734, 3869.264839680508, 3896.3181443247886, 3911.6088391160656, 3925.9607403925597, 3928.9607103929166, 3338.432466480704, 3954.5056867891512, 3954.723169378116, 3962.643362097425, 3987.6610488108836, 3861.9613803861603, 4369.344598310216, 4652.603908986875, 4665.883352916137, 4683.578477937002, 5447.0, 5780.624259423124, 5782.537397008229, 5805.274340707411, 5850.327212370632, 1268.9214167957164, 1289.7936330187124, 1310.3644732304824, 1318.736252749454, 1357.3484727330936, 745.8843879198732, 785.2226295966951, 807.7940125268136, 854.7521218846465, 855.4653341661461, 4574.062317224961, 4866.513348665105, 5120.078385890575, 5154.082896998309, 5154.433012368637, 5672.347680016768, 5791.0, 5946.524278057745, 5956.098755315275, 5964.671943043174, 4811.326414302001, 4834.879128021757, 5084.872878178076, 5307.920381194285, 5343.210024641789, 2939.2357986923125, 3041.7718671099756, 3073.5389691546, 3129.7809153359044, 3134.2477805326557, 5252.161743389859, 5969.0, 6085.36103709113, 6112.480439162721, 6164.568480206342, 5259.15853463444, 5822.282426684138, 5988.071848863346, 6009.399060093956, 6060.909086363708, 5799.681017544076, 5960.58596268512, 6059.454649081604, 6112.327643959161, 6184.721687523982, 4851.660383773243, 5488.670679759227, 5781.624194427349, 5918.638713095926, 5948.256467941507, 4857.975710121426, 5815.67373050783, 5820.563457740602, 5872.3297938788255, 5879.2944846618675, 6037.366076561898, 6108.328083910677, 6114.6942652868065, 6164.660943648142, 6183.443490085914, 3742.315957818938, 3797.1076796952143, 3823.1589050408866, 3842.8270727816757, 3902.9804850976698, 4901.162064225915, 5873.942690315785, 5938.703064846827, 5970.104484327386, 5974.761009559569, 4622.930656040229, 5386.8383948481605, 5507.642003269774, 5745.741441635053, 5807.82576522705, 5299.6290259681455, 5397.7840886365675, 5477.589180811376, 5838.620489668158, 5843.386444423274, 5351.277577526984, 5682.516986056232, 5704.0, 5707.743151558273, 5709.857253568694, 4979.377577802775, 5274.446183150714, 5718.571107167062, 5751.971240143773, 5763.394843541536, 5615.101583746581, 5619.325680918152, 5625.493705566519, 5626.859328516656, 5628.8874222514505], "value": 4151.725565978543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.4464332995287, 723.645413747264, 734.2473964186711, 772.0272456704553, 798.209772325398, 15220.162891040989, 16592.33630654774, 16609.91695041526, 16622.418215362464, 16641.167941602933, 14804.703905921875, 14945.252737363142, 15181.77227341588, 15368.001079929796, 15375.077495350286, 884.9911500884989, 885.6280362247871, 891.2602539891874, 891.9420237684562, 893.2452077994117, 715.5670819154415, 730.8684436801374, 731.6049333359979, 735.9668814903342, 737.292199488489, 761.3376362564577, 918.4030380252823, 949.0888746803078, 957.9089986451311, 958.0276019839868, 818.8290744235741, 904.1365495951351, 933.6918816790477, 933.7292185266265, 935.4527601353217, 548.5227851768987, 688.0401839434012, 689.1351354050685, 689.9379055884995, 689.9896501552481, 15050.924745376204, 15609.375624974988, 15611.219439028004, 15626.609334766608, 15655.686886262325, 14823.258837058103, 15029.624259393602, 15050.247487625684, 15325.0, 15413.5375938722, 12038.518459261619, 12050.216735912138, 12089.455974481169, 12275.2021118627, 12615.684607884785, 3926.5326383718043, 3962.1679447316096, 3978.2839088964265, 3986.940195897064, 4084.0, 113312.0, 114875.0, 115601.42199289033, 116462.0, 118965.0, 11641.76716465666, 11747.823782643267, 11751.353675547756, 12071.517139314505, 12171.452284647212, 24013.639795403084, 24428.145014924394, 24468.02127914899, 24485.63271550928, 24646.383840403774, 7977.880331795028, 8179.018517777905, 8273.751787446387, 8363.749087527383, 8385.496870187686, 4449.510553839075, 4514.525974772667, 4544.091181763661, 4609.285560738091, 4676.13491504066, 3530.3468858261303, 3677.9448308275396, 3678.503402040744, 3680.282344942764, 3687.59436461989, 663.9601623902581, 675.3506914658941, 682.74738346812, 692.9861402771917, 696.1263614164192, 568.7326956330518, 596.6241268001131, 683.5556888022825, 697.3688811625487, 698.979030629082, 9551.767300470927, 9587.664431744855, 9745.756356091013, 9746.463944482979, 9899.901000990041, 572.384204186862, 645.2966266769245, 683.7165160270906, 703.802935178151, 710.4068103133941, 4562.099102187678, 4740.786664600101, 4795.2737014674685, 4798.136335459651, 4808.759562021885, 128734.0, 131664.68335316525, 153889.0, 155619.66570501451, 158828.20585897227, 102042.97957020483, 133134.66865331418, 133506.0, 133699.0, 136420.31789840988, 105923.35191619962, 110924.4453777726, 116486.0, 117424.0, 118053.40973295251, 8654.061952949243, 9240.306976976619, 9265.861012084959, 9308.255339572683, 9525.571349289437, 50915.745421274136, 58173.12740308814, 58299.70850145722, 65319.0, 68548.0, 63255.68372158294, 63464.36535634584, 95124.04875951429, 98796.0, 100033.49983250328, 11806.940965295118, 11917.702057448287, 11973.16187866841, 12048.578299759467, 12569.497220111092, 13462.663433414244, 13547.32263386807, 13616.319184040956, 13907.58277251684, 14119.576412707635, 11219.55121795119, 11654.184207105422, 12633.30516821547, 13039.804402934153, 13060.934695326463, 12612.936935315265, 12648.114631975674, 12686.619401417973, 12824.358782061046, 13087.672808179872, 12257.448414821176, 12269.631911042683, 12317.568885088978, 12619.36903154857, 12789.296588687712, 10548.367097974146, 10550.8417373741, 10815.51330190159, 11090.44547772592, 11150.442477876237, 7814.33578145864, 10113.431464119865, 10525.315854469434, 10887.292325998786, 10906.945465272624], "value": 7845.570567580256, "unit": "score"}], "name": "dromaeo_css", "value": 7501.826766193648, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 112.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 109.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 103.0, 153.0, 106.0, 108.0, 137.0, 136.0, 176.0, 107.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 185.0, 178.0, 181.0, 183.0, 177.0, 181.0, 182.0, 182.0], "value": 181.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 56.0, 110.0, 57.0, 57.0, 59.0, 65.0, 100.0, 57.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 118.0, 126.0, 102.0, 69.0, 68.0, 97.0, 69.0, 67.0, 101.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 87.0, 88.0, 86.0, 91.0, 87.0, 89.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 103.0, 144.0, 96.0, 108.0, 149.0, 118.0, 94.0, 96.0, 95.0], "value": 109.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 83.0, 83.0, 83.0, 85.0, 84.0, 82.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 53.0, 53.0, 53.0, 54.0, 55.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 69.0, 77.0, 71.0, 71.0, 70.0, 69.0, 71.0, 76.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 82.0, 85.0, 82.0, 85.0, 85.0, 83.0, 82.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 146.0, 137.0, 144.0, 151.0, 135.0, 133.0, 180.0, 139.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 164.0, 164.0, 166.0, 162.0, 166.0, 161.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 85.0, 87.0, 84.0, 80.0, 65.0, 66.0, 65.0, 65.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1453.1999999999998, "alertThreshold": 2.0}]} 08:31:15 INFO - Return code: 0 08:31:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:31:15 INFO - # TBPL SUCCESS # 08:31:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:15 INFO - 2016-07-08 08:31:15.439 screenresolution[1668:10396] starting screenresolution argv=screenresolution get 08:31:15 INFO - 2016-07-08 08:31:15.442 screenresolution[1668:10396] Display 0: 1600x1200x32@60 08:31:15 INFO - 2016-07-08 08:31:15.447 screenresolution[1669:10399] starting screenresolution argv=screenresolution list 08:31:15 INFO - Available Modes on Display 0 08:31:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:15 INFO - Intel Iris: 08:31:15 INFO - Chipset Model: Intel Iris 08:31:15 INFO - Type: GPU 08:31:15 INFO - Bus: Built-In 08:31:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:15 INFO - Vendor: Intel (0x8086) 08:31:15 INFO - Device ID: 0x0a2e 08:31:15 INFO - Revision ID: 0x0009 08:31:15 INFO - Displays: 08:31:15 INFO - Display: 08:31:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:15 INFO - Display Serial Number: 1600x1200 60 08:31:15 INFO - Main Display: Yes 08:31:15 INFO - Mirror: Off 08:31:15 INFO - Online: Yes 08:31:15 INFO - Rotation: Supported 08:31:15 INFO - Return code: 0 08:31:15 INFO - Running post-action listener: _resource_record_post_action 08:31:15 INFO - ##### 08:31:15 INFO - ##### Finished run-tests step (success) 08:31:15 INFO - ##### 08:31:15 INFO - Running post-run listener: _resource_record_post_run 08:31:15 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31288832; Write bytes: 708086784; Read time: 192; Write time: 1940 08:31:15 INFO - TinderboxPrint: CPU usage
53.3% 08:31:15 INFO - TinderboxPrint: I/O read bytes / time
31,288,832 / 192 08:31:15 INFO - TinderboxPrint: I/O write bytes / time
708,086,784 / 1,940 08:31:15 INFO - TinderboxPrint: CPU idle
2,263.1 (46.7%) 08:31:15 INFO - TinderboxPrint: CPU system
961.2 (19.9%) 08:31:15 INFO - TinderboxPrint: CPU user
1,617.5 (33.4%) 08:31:15 INFO - TinderboxPrint: Swap in / out
434,511,872 / 0 08:31:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390363648; Write bytes: 385914880; Read time: 28959; Write time: 789 08:31:15 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30363136; Write bytes: 297042944; Read time: 181; Write time: 1096 08:31:16 INFO - Running post-run listener: _upload_blobber_files 08:31:16 INFO - Blob upload gear active. 08:31:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:16 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 08:31:16 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'] 08:31:16 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 08:31:16 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:17 INFO - (blobuploader) - INFO - Done attempting. 08:31:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:19 INFO - (blobuploader) - INFO - Done attempting. 08:31:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:21 INFO - (blobuploader) - INFO - Done attempting. 08:31:21 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:21 INFO - Return code: 0 08:31:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce429c19db37e8d5eed5a7c2d95d3ee97737f5fe3c13c03654425606ac4793374979f649476297731340d9392c10ca6e4715b96dd648353d75dd2105f22463a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc1a3f306cd78fed740f309016b510bed284bf9a773ec867797dc487ca934257c1f6a86035d626e2f0676e4ca25b9646ac1dfd329c4b54223746acd963b85fce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d451078d91e91b6ad159f3be2c4edd4447286c82a0082422b4f6b3f3f4865985d642d9e03cf66df081a0386d7ec320bcd4fc7d8dc3a0a7dec12497092b1458"} 08:31:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:21 INFO - Contents: 08:31:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce429c19db37e8d5eed5a7c2d95d3ee97737f5fe3c13c03654425606ac4793374979f649476297731340d9392c10ca6e4715b96dd648353d75dd2105f22463a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc1a3f306cd78fed740f309016b510bed284bf9a773ec867797dc487ca934257c1f6a86035d626e2f0676e4ca25b9646ac1dfd329c4b54223746acd963b85fce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d451078d91e91b6ad159f3be2c4edd4447286c82a0082422b4f6b3f3f4865985d642d9e03cf66df081a0386d7ec320bcd4fc7d8dc3a0a7dec12497092b1458"} 08:31:21 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:21 INFO - Copying logs to upload dir... 08:31:21 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.048235 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-07-08 08:31:21.372641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 08:31:21.376352) ========= 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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/ce429c19db37e8d5eed5a7c2d95d3ee97737f5fe3c13c03654425606ac4793374979f649476297731340d9392c10ca6e4715b96dd648353d75dd2105f22463a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc1a3f306cd78fed740f309016b510bed284bf9a773ec867797dc487ca934257c1f6a86035d626e2f0676e4ca25b9646ac1dfd329c4b54223746acd963b85fce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d451078d91e91b6ad159f3be2c4edd4447286c82a0082422b4f6b3f3f4865985d642d9e03cf66df081a0386d7ec320bcd4fc7d8dc3a0a7dec12497092b1458"} build_url:https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/NvKW3vkpRXG848-O_wNOzg/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/ce429c19db37e8d5eed5a7c2d95d3ee97737f5fe3c13c03654425606ac4793374979f649476297731340d9392c10ca6e4715b96dd648353d75dd2105f22463a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc1a3f306cd78fed740f309016b510bed284bf9a773ec867797dc487ca934257c1f6a86035d626e2f0676e4ca25b9646ac1dfd329c4b54223746acd963b85fce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d451078d91e91b6ad159f3be2c4edd4447286c82a0082422b4f6b3f3f4865985d642d9e03cf66df081a0386d7ec320bcd4fc7d8dc3a0a7dec12497092b1458"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 08:31:21.409916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:31:21.410244) ========= 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.8RSS1UiFwq/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.NGPaRFbWQQ/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.005158 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 08:31:21.458564) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 08:31:21.458883) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 08:31:21.459200) ========= ========= Total master_lag: 0.18 =========