builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0223 starttime: 1468750855.98 results: success (0) buildid: 20160717010853 builduid: 9e863a19fac64e50a016e772a3f82a7f revision: 711963e8daa312ae06409f8ab5c06612cb0b8f7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:55.975360) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:55.975791) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:55.976075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:56.003769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:56.004173) ========= 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.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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-17 03:20:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-07-17 03:20:56 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.143328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:56.175243) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:56.175624) ========= 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.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.025545 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:56.226620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:20:56.230031) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 711963e8daa312ae06409f8ab5c06612cb0b8f7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 711963e8daa312ae06409f8ab5c06612cb0b8f7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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-17 03:20:56,312 truncating revision to first 12 chars 2016-07-17 03:20:56,312 Setting DEBUG logging. 2016-07-17 03:20:56,312 attempt 1/10 2016-07-17 03:20:56,312 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/711963e8daa3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 03:20:57,052 unpacking tar archive at: mozilla-central-711963e8daa3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.999322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 03:20:57.251156) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:57.251491) ========= script_repo_revision: 711963e8daa312ae06409f8ab5c06612cb0b8f7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:57.254898) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:57.255176) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 03:20:57.273219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-17 03:20:57.273772) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:20:57 INFO - MultiFileLogger online at 20160717 03:20:57 in /builds/slave/test 03:20:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:20:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:57 INFO - {'append_to_log': False, 03:20:57 INFO - 'base_work_dir': '/builds/slave/test', 03:20:57 INFO - 'blob_upload_branch': 'Firefox', 03:20:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:57 INFO - 'branch': 'Firefox', 03:20:57 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:57 INFO - 'config_files': ('talos/mac_config.py',), 03:20:57 INFO - 'default_actions': ('clobber', 03:20:57 INFO - 'read-buildbot-config', 03:20:57 INFO - 'download-and-extract', 03:20:57 INFO - 'populate-webroot', 03:20:57 INFO - 'create-virtualenv', 03:20:57 INFO - 'install', 03:20:57 INFO - 'run-tests'), 03:20:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:57 INFO - 'download_minidump_stackwalk': True, 03:20:57 INFO - 'download_symbols': 'ondemand', 03:20:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:57 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:57 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:57 INFO - 'installer_path': 'installer.exe', 03:20:57 INFO - 'log_level': 'info', 03:20:57 INFO - 'log_name': 'talos', 03:20:57 INFO - 'log_to_console': True, 03:20:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:57 INFO - 'opt_config_files': (), 03:20:57 INFO - 'pip_index': False, 03:20:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:57 INFO - 'cmd': ('bash', 03:20:57 INFO - '-c', 03:20:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:57 INFO - 'enabled': True, 03:20:57 INFO - 'halt_on_failure': False, 03:20:57 INFO - 'name': 'check_screen_resolution'},), 03:20:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:57 INFO - 'cmd': ('bash', 03:20:57 INFO - '-c', 03:20:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:57 INFO - 'enabled': True, 03:20:57 INFO - 'halt_on_failure': False, 03:20:57 INFO - 'name': 'check_screen_resolution'},), 03:20:57 INFO - 'run_cmd_checks_enabled': True, 03:20:57 INFO - 'sps_profile': False, 03:20:57 INFO - 'sps_profile_interval': 0, 03:20:57 INFO - 'suite': 'dromaeojs', 03:20:57 INFO - 'system_bits': '32', 03:20:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:57 INFO - 'title': 't-yosemite-r7-0223', 03:20:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:57 INFO - 'use_talos_json': True, 03:20:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:57 INFO - 'work_dir': 'build'} 03:20:57 INFO - ##### 03:20:57 INFO - ##### Running clobber step. 03:20:57 INFO - ##### 03:20:57 INFO - Running pre-action listener: _resource_record_pre_action 03:20:57 INFO - Running main action method: clobber 03:20:57 INFO - rmtree: /builds/slave/test/build 03:20:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:59 INFO - Running post-action listener: _resource_record_post_action 03:20:59 INFO - ##### 03:20:59 INFO - ##### Finished clobber step (success) 03:20:59 INFO - ##### 03:20:59 INFO - ##### 03:20:59 INFO - ##### Running read-buildbot-config step. 03:20:59 INFO - ##### 03:20:59 INFO - Running pre-action listener: _resource_record_pre_action 03:20:59 INFO - Running main action method: read_buildbot_config 03:20:59 INFO - Using buildbot properties: 03:20:59 INFO - { 03:20:59 INFO - "project": "", 03:20:59 INFO - "product": "firefox", 03:20:59 INFO - "script_repo_revision": "production", 03:20:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:20:59 INFO - "repository": "", 03:20:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:20:59 INFO - "buildid": "20160717010853", 03:20:59 INFO - "pgo_build": "False", 03:20:59 INFO - "basedir": "/builds/slave/test", 03:20:59 INFO - "buildnumber": 11, 03:20:59 INFO - "slavename": "t-yosemite-r7-0223", 03:20:59 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:20:59 INFO - "platform": "yosemite_r7", 03:20:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:20:59 INFO - "branch": "mozilla-central", 03:20:59 INFO - "repo_path": "mozilla-central", 03:20:59 INFO - "revision": "711963e8daa312ae06409f8ab5c06612cb0b8f7b", 03:20:59 INFO - "stage_platform": "macosx64", 03:20:59 INFO - "builduid": "9e863a19fac64e50a016e772a3f82a7f", 03:20:59 INFO - "slavebuilddir": "test" 03:20:59 INFO - } 03:20:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:20:59 INFO - Running post-action listener: _resource_record_post_action 03:20:59 INFO - ##### 03:20:59 INFO - ##### Finished read-buildbot-config step (success) 03:20:59 INFO - ##### 03:20:59 INFO - ##### 03:20:59 INFO - ##### Running download-and-extract step. 03:20:59 INFO - ##### 03:20:59 INFO - Running pre-action listener: _resource_record_pre_action 03:20:59 INFO - Running main action method: download_and_extract 03:20:59 INFO - mkdir: /builds/slave/test/build/tests 03:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:20:59 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:20:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:20:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:20:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:00 INFO - Downloaded 1199 bytes. 03:21:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:21:00 INFO - Using the following test package requirements: 03:21:00 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:21:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:21:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:21:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'jsshell-mac.zip'], 03:21:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:21:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:21:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:21:00 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:21:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:21:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:21:00 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:21:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:21:00 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')]} 03:21:00 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:21:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:21:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:01 INFO - Downloaded 18835903 bytes. 03:21:01 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'] 03:21:01 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 03:21:04 INFO - Return code: 0 03:21:04 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 03:21: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')]} 03:21:04 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:06 INFO - Downloaded 18835903 bytes. 03:21: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'] 03:21: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 03:21:09 INFO - Return code: 0 03:21:09 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')]} 03:21:09 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:21:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:21:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:21:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:10 INFO - Downloaded 14403317 bytes. 03:21:10 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'] 03:21:10 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 03:21:10 INFO - Return code: 0 03:21: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')]} 03:21:10 INFO - https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:21:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:21:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E_ga-2xZTAOUAbuKd7ijHg/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 03:21:14 INFO - Downloaded 112771472 bytes. 03:21:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:14 INFO - mkdir: /builds/slave/test/properties 03:21:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:14 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:14 INFO - Contents: 03:21:14 INFO - build_url:https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:21:15 INFO - Running post-action listener: _resource_record_post_action 03:21:15 INFO - Running post-action listener: set_extra_try_arguments 03:21:15 INFO - ##### 03:21:15 INFO - ##### Finished download-and-extract step (success) 03:21:15 INFO - ##### 03:21:15 INFO - ##### 03:21:15 INFO - ##### Running populate-webroot step. 03:21:15 INFO - ##### 03:21:15 INFO - Running pre-action listener: _resource_record_pre_action 03:21:15 INFO - Running main action method: populate_webroot 03:21:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:15 INFO - '--tppagecycles', 03:21:15 INFO - '7'], 03:21:15 INFO - 'tests': ['tsvgm']}}, 03:21:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['damp', 'tps']}, 03:21:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'tests': ['damp', 'tps']}, 03:21:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['dromaeo_dom']}, 03:21:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['basic_compositor_video']}, 03:21:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['a11yr', 03:21:15 INFO - 'ts_paint', 03:21:15 INFO - 'tpaint', 03:21:15 INFO - 'sessionrestore', 03:21:15 INFO - 'sessionrestore_no_auto_restore', 03:21:15 INFO - 'tabpaint']}, 03:21:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['tsvgx', 03:21:15 INFO - 'tsvgr_opacity', 03:21:15 INFO - 'tart', 03:21:15 INFO - 'tscrollx', 03:21:15 INFO - 'cart']}, 03:21:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:15 INFO - 'tsvgr_opacity', 03:21:15 INFO - 'tart', 03:21:15 INFO - 'tscrollx', 03:21:15 INFO - 'cart']}, 03:21:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'talos_options': ['--disable-e10s'], 03:21:15 INFO - 'tests': ['tp5o']}, 03:21:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'tests': ['tp5o']}, 03:21:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'talos_options': ['--disable-e10s', 03:21:15 INFO - '--xperf_path', 03:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:15 INFO - 'tests': ['tp5n']}, 03:21:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:21:15 INFO - 'talos_options': ['--xperf_path', 03:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:15 INFO - 'tests': ['tp5n']}}, 03:21:15 INFO - 'talos.zip': {'path': '', 03:21:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:15 INFO - Running post-action listener: _resource_record_post_action 03:21:15 INFO - ##### 03:21:15 INFO - ##### Finished populate-webroot step (success) 03:21:15 INFO - ##### 03:21:15 INFO - ##### 03:21:15 INFO - ##### Running create-virtualenv step. 03:21:15 INFO - ##### 03:21:15 INFO - Running pre-action listener: _resource_record_pre_action 03:21:15 INFO - Running main action method: create_virtualenv 03:21:15 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:21:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:15 INFO - Using real prefix '/tools/python27' 03:21:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:15 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:18 INFO - Installing pip.................done. 03:21:18 INFO - Return code: 0 03:21:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:18 INFO - 'HOME': '/Users/cltbld', 03:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:18 INFO - 'LOGNAME': 'cltbld', 03:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:18 INFO - 'MOZ_NO_REMOTE': '1', 03:21:18 INFO - 'NO_EM_RESTART': '1', 03:21:18 INFO - 'PAGER': '/bin/cat', 03:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:18 INFO - 'PWD': '/builds/slave/test', 03:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:18 INFO - 'SHELL': '/bin/bash', 03:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:18 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:18 INFO - 'USER': 'cltbld', 03:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:18 INFO - 'XPC_FLAGS': '0x0', 03:21:18 INFO - 'XPC_SERVICE_NAME': '0', 03:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:21:19 INFO - Cleaning up... 03:21:19 INFO - Return code: 0 03:21:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:19 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:19 INFO - 'HOME': '/Users/cltbld', 03:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:19 INFO - 'LOGNAME': 'cltbld', 03:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:19 INFO - 'MOZ_NO_REMOTE': '1', 03:21:19 INFO - 'NO_EM_RESTART': '1', 03:21:19 INFO - 'PAGER': '/bin/cat', 03:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:19 INFO - 'PWD': '/builds/slave/test', 03:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:19 INFO - 'SHELL': '/bin/bash', 03:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:19 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:19 INFO - 'USER': 'cltbld', 03:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:19 INFO - 'XPC_FLAGS': '0x0', 03:21:19 INFO - 'XPC_SERVICE_NAME': '0', 03:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:19 INFO - Downloading/unpacking psutil>=3.1.1 03:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:22 INFO - Installing collected packages: psutil 03:21:22 INFO - Running setup.py install for psutil 03:21:22 INFO - building 'psutil._psutil_osx' extension 03:21:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:21:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:21:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:21:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:21:23 INFO - building 'psutil._psutil_posix' extension 03:21:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:21:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:23 INFO - ^ 03:21:23 INFO - 1 warning generated. 03:21:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:21:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:23 INFO - Successfully installed psutil 03:21:23 INFO - Cleaning up... 03:21:23 INFO - Return code: 0 03:21:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:23 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:23 INFO - 'HOME': '/Users/cltbld', 03:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:23 INFO - 'LOGNAME': 'cltbld', 03:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:23 INFO - 'MOZ_NO_REMOTE': '1', 03:21:23 INFO - 'NO_EM_RESTART': '1', 03:21:23 INFO - 'PAGER': '/bin/cat', 03:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:23 INFO - 'PWD': '/builds/slave/test', 03:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:23 INFO - 'SHELL': '/bin/bash', 03:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:23 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:23 INFO - 'USER': 'cltbld', 03:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:23 INFO - 'XPC_FLAGS': '0x0', 03:21:23 INFO - 'XPC_SERVICE_NAME': '0', 03:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:26 INFO - Installing collected packages: mozsystemmonitor 03:21:26 INFO - Running setup.py install for mozsystemmonitor 03:21:26 INFO - Successfully installed mozsystemmonitor 03:21:26 INFO - Cleaning up... 03:21:26 INFO - Return code: 0 03:21:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21: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')]} 03:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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')]} 03:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:26 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:26 INFO - 'HOME': '/Users/cltbld', 03:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:26 INFO - 'LOGNAME': 'cltbld', 03:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:26 INFO - 'MOZ_NO_REMOTE': '1', 03:21:26 INFO - 'NO_EM_RESTART': '1', 03:21:26 INFO - 'PAGER': '/bin/cat', 03:21:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:26 INFO - 'PWD': '/builds/slave/test', 03:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:26 INFO - 'SHELL': '/bin/bash', 03:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:26 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:26 INFO - 'USER': 'cltbld', 03:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:26 INFO - 'XPC_FLAGS': '0x0', 03:21:26 INFO - 'XPC_SERVICE_NAME': '0', 03:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:26 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:29 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:29 INFO - warning: no files found matching '*.txt' 03:21:29 INFO - no previously-included directories found matching 'build' 03:21:29 INFO - no previously-included directories found matching 'dist' 03:21:29 INFO - no previously-included directories found matching '.git*' 03:21:29 INFO - Installing collected packages: jsonschema, functools32 03:21:29 INFO - Running setup.py install for functools32 03:21:29 INFO - warning: no files found matching '*.txt' 03:21:29 INFO - no previously-included directories found matching 'build' 03:21:29 INFO - no previously-included directories found matching 'dist' 03:21:29 INFO - no previously-included directories found matching '.git*' 03:21:29 INFO - Successfully installed jsonschema functools32 03:21:29 INFO - Cleaning up... 03:21:30 INFO - Return code: 0 03:21:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:30 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')]} 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 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')]} 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:30 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:21:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:30 INFO - 'HOME': '/Users/cltbld', 03:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:30 INFO - 'LOGNAME': 'cltbld', 03:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:30 INFO - 'MOZ_NO_REMOTE': '1', 03:21:30 INFO - 'NO_EM_RESTART': '1', 03:21:30 INFO - 'PAGER': '/bin/cat', 03:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:30 INFO - 'PWD': '/builds/slave/test', 03:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:30 INFO - 'SHELL': '/bin/bash', 03:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:30 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:30 INFO - 'USER': 'cltbld', 03:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:30 INFO - 'XPC_FLAGS': '0x0', 03:21:30 INFO - 'XPC_SERVICE_NAME': '0', 03:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:30 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:33 INFO - Downloading docopt-0.6.1.tar.gz 03:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:33 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:33 INFO - Running setup.py install for blobuploader 03:21:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:34 INFO - Running setup.py install for requests 03:21:34 INFO - Running setup.py install for docopt 03:21:34 INFO - Successfully installed blobuploader requests docopt 03:21:34 INFO - Cleaning up... 03:21:34 INFO - Return code: 0 03:21:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:34 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')]} 03:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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')]} 03:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:34 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:34 INFO - 'HOME': '/Users/cltbld', 03:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:34 INFO - 'LOGNAME': 'cltbld', 03:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:34 INFO - 'MOZ_NO_REMOTE': '1', 03:21:34 INFO - 'NO_EM_RESTART': '1', 03:21:34 INFO - 'PAGER': '/bin/cat', 03:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:34 INFO - 'PWD': '/builds/slave/test', 03:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:34 INFO - 'SHELL': '/bin/bash', 03:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:34 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:34 INFO - 'USER': 'cltbld', 03:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:34 INFO - 'XPC_FLAGS': '0x0', 03:21:34 INFO - 'XPC_SERVICE_NAME': '0', 03:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Aheb5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ZNaB05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-sxIxYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-R4mok6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Z4tRMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-2uNBHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-eOwZxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-R23GhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:35 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-UqIBdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-KN0eZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-NFbqtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-e5jMOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-x7qQB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-IIyJrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-MhyLLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ckDyRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:36 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-7EtARy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:36 INFO - Running setup.py install for manifestparser 03:21:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:37 INFO - Running setup.py install for mozcrash 03:21:37 INFO - Running setup.py install for mozdebug 03:21:37 INFO - Running setup.py install for mozdevice 03:21:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:21:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:37 INFO - Running setup.py install for mozfile 03:21:37 INFO - Running setup.py install for mozhttpd 03:21:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:37 INFO - Running setup.py install for mozinfo 03:21:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:37 INFO - Running setup.py install for mozInstall 03:21:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for mozleak 03:21:38 INFO - Running setup.py install for mozlog 03:21:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for moznetwork 03:21:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for mozprocess 03:21:38 INFO - Running setup.py install for mozprofile 03:21:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:38 INFO - Running setup.py install for mozrunner 03:21:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Running setup.py install for mozscreenshot 03:21:39 INFO - Running setup.py install for moztest 03:21:39 INFO - Running setup.py install for mozversion 03:21:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:39 INFO - Cleaning up... 03:21:39 INFO - Return code: 0 03:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21: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')]} 03:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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')]} 03:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:39 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:39 INFO - 'HOME': '/Users/cltbld', 03:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:39 INFO - 'LOGNAME': 'cltbld', 03:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:39 INFO - 'MOZ_NO_REMOTE': '1', 03:21:39 INFO - 'NO_EM_RESTART': '1', 03:21:39 INFO - 'PAGER': '/bin/cat', 03:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:39 INFO - 'PWD': '/builds/slave/test', 03:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:39 INFO - 'SHELL': '/bin/bash', 03:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:39 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:39 INFO - 'USER': 'cltbld', 03:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:39 INFO - 'XPC_FLAGS': '0x0', 03:21:39 INFO - 'XPC_SERVICE_NAME': '0', 03:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-6p_55Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-5diAwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-__ZX5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-hXuSmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-osnrmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-CtH2IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-wEXC7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-m7EKC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-WUX6KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-vlVbNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:40 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)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-x0TKut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:40 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-aoQxJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:41 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-vz5VIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:41 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-zfpvry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:41 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-YxpsCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:41 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ITSGKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:41 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-HktZ76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:21:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:44 INFO - Downloading blessings-1.6.tar.gz 03:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:44 INFO - Installing collected packages: blessings 03:21:44 INFO - Running setup.py install for blessings 03:21:44 INFO - Successfully installed blessings 03:21:44 INFO - Cleaning up... 03:21:44 INFO - Return code: 0 03:21:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:44 INFO - Reading from file tmpfile_stdout 03:21:44 INFO - Current package versions: 03:21:44 INFO - blessings == 1.6 03:21:44 INFO - blobuploader == 1.2.4 03:21:44 INFO - docopt == 0.6.1 03:21:44 INFO - functools32 == 3.2.3-2 03:21:44 INFO - jsonschema == 2.5.1 03:21:44 INFO - manifestparser == 1.1 03:21:44 INFO - mozInstall == 1.12 03:21:44 INFO - mozcrash == 0.17 03:21:44 INFO - mozdebug == 0.1 03:21:44 INFO - mozdevice == 0.48 03:21:44 INFO - mozfile == 1.2 03:21:44 INFO - mozhttpd == 0.7 03:21:44 INFO - mozinfo == 0.9 03:21:44 INFO - mozleak == 0.1 03:21:44 INFO - mozlog == 3.2 03:21:44 INFO - moznetwork == 0.27 03:21:44 INFO - mozprocess == 0.23 03:21:44 INFO - mozprofile == 0.28 03:21:44 INFO - mozrunner == 6.12 03:21:44 INFO - mozscreenshot == 0.1 03:21:44 INFO - mozsystemmonitor == 0.3 03:21:44 INFO - moztest == 0.7 03:21:44 INFO - mozversion == 1.4 03:21:44 INFO - psutil == 3.1.1 03:21:44 INFO - requests == 1.2.3 03:21:44 INFO - wsgiref == 0.1.2 03:21:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:44 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:44 INFO - 'HOME': '/Users/cltbld', 03:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:44 INFO - 'LOGNAME': 'cltbld', 03:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:44 INFO - 'MOZ_NO_REMOTE': '1', 03:21:44 INFO - 'NO_EM_RESTART': '1', 03:21:44 INFO - 'PAGER': '/bin/cat', 03:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:44 INFO - 'PWD': '/builds/slave/test', 03:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:44 INFO - 'SHELL': '/bin/bash', 03:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:44 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:44 INFO - 'USER': 'cltbld', 03:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:44 INFO - 'XPC_FLAGS': '0x0', 03:21:44 INFO - 'XPC_SERVICE_NAME': '0', 03:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:21:44 INFO - Cleaning up... 03:21:44 INFO - Return code: 0 03:21:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:44 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 0x1052b1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105346618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89f2572a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, '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.RgIk5POKKT/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.pzvVqPBExr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:21:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:21:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:44 INFO - 'HOME': '/Users/cltbld', 03:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:44 INFO - 'LOGNAME': 'cltbld', 03:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:44 INFO - 'MOZ_NO_REMOTE': '1', 03:21:44 INFO - 'NO_EM_RESTART': '1', 03:21:44 INFO - 'PAGER': '/bin/cat', 03:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:44 INFO - 'PWD': '/builds/slave/test', 03:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:44 INFO - 'SHELL': '/bin/bash', 03:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:21:44 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:44 INFO - 'USER': 'cltbld', 03:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:44 INFO - 'XPC_FLAGS': '0x0', 03:21:44 INFO - 'XPC_SERVICE_NAME': '0', 03:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:21:45 INFO - Cleaning up... 03:21:45 INFO - Return code: 0 03:21:45 INFO - Running post-action listener: _resource_record_post_action 03:21:45 INFO - Running post-action listener: _start_resource_monitoring 03:21:45 INFO - Starting resource monitoring. 03:21:45 INFO - ##### 03:21:45 INFO - ##### Finished create-virtualenv step (success) 03:21:45 INFO - ##### 03:21:45 INFO - ##### 03:21:45 INFO - ##### Running install step. 03:21:45 INFO - ##### 03:21:45 INFO - Running pre-action listener: _resource_record_pre_action 03:21:45 INFO - Running main action method: install 03:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:45 INFO - Reading from file tmpfile_stdout 03:21:45 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:45 INFO - Reading from file tmpfile_stdout 03:21:45 INFO - Output received: 03:21:45 INFO - Usage: mozinstall [options] installer 03:21:45 INFO - Options: 03:21:45 INFO - -h, --help show this help message and exit 03:21:45 INFO - -d DEST, --destination=DEST 03:21:45 INFO - Directory to install application into. [default: 03:21:45 INFO - "/builds/slave/test"] 03:21:45 INFO - --app=APP Application being installed. [default: firefox] 03:21:45 INFO - mkdir: /builds/slave/test/build/application 03:21:45 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'] 03:21:45 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 03:22:20 INFO - Reading from file tmpfile_stdout 03:22:20 INFO - Output received: 03:22:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:22:20 INFO - Running post-action listener: _resource_record_post_action 03:22:20 INFO - ##### 03:22:20 INFO - ##### Finished install step (success) 03:22:20 INFO - ##### 03:22:20 INFO - ##### 03:22:20 INFO - ##### Running run-tests step. 03:22:20 INFO - ##### 03:22:20 INFO - Running pre-action listener: _resource_record_pre_action 03:22:20 INFO - Running main action method: run_tests 03:22:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:20 INFO - 2016-07-17 03:22:20.093 screenresolution[1640:7440] starting screenresolution argv=screenresolution get 03:22:20 INFO - 2016-07-17 03:22:20.096 screenresolution[1640:7440] Display 0: 1600x1200x32@60 03:22:20 INFO - 2016-07-17 03:22:20.101 screenresolution[1641:7442] starting screenresolution argv=screenresolution list 03:22:20 INFO - Available Modes on Display 0 03:22:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:20 INFO - Intel Iris: 03:22:20 INFO - Chipset Model: Intel Iris 03:22:20 INFO - Type: GPU 03:22:20 INFO - Bus: Built-In 03:22:20 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:20 INFO - Vendor: Intel (0x8086) 03:22:20 INFO - Device ID: 0x0a2e 03:22:20 INFO - Revision ID: 0x0009 03:22:20 INFO - Displays: 03:22:20 INFO - Display: 03:22:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:20 INFO - Display Serial Number: 1600x1200 60 03:22:20 INFO - Main Display: Yes 03:22:20 INFO - Mirror: Off 03:22:20 INFO - Online: Yes 03:22:20 INFO - Rotation: Supported 03:22:20 INFO - Return code: 0 03:22:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:20 INFO - Python 2.7.3 03:22:20 INFO - Return code: 0 03:22:20 INFO - grabbing minidump binary from tooltool 03:22:20 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')]} 03:22:20 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 0x105215d30>, '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 0x104f7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10520db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:22:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:22:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:22:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:20 INFO - Return code: 0 03:22:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0223 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:22:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RgIk5POKKT/Render', 03:22:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:20 INFO - 'HOME': '/Users/cltbld', 03:22:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:20 INFO - 'LOGNAME': 'cltbld', 03:22:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:20 INFO - 'MOZ_NO_REMOTE': '1', 03:22:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:20 INFO - 'NO_EM_RESTART': '1', 03:22:20 INFO - 'PAGER': '/bin/cat', 03:22:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:20 INFO - 'PWD': '/builds/slave/test', 03:22:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:20 INFO - 'SHELL': '/bin/bash', 03:22:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pzvVqPBExr/Listeners', 03:22:20 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 03:22:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:20 INFO - 'USER': 'cltbld', 03:22:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:20 INFO - 'XPC_FLAGS': '0x0', 03:22:20 INFO - 'XPC_SERVICE_NAME': '0', 03:22:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:22:20 INFO - mozversion application_buildid: 20160717010853 03:22:20 INFO - mozversion application_changeset: 711963e8daa312ae06409f8ab5c06612cb0b8f7b 03:22:20 INFO - mozversion application_display_name: Nightly 03:22:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:20 INFO - mozversion application_name: Firefox 03:22:20 INFO - mozversion application_remotingname: firefox 03:22:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:22:20 INFO - mozversion application_vendor: Mozilla 03:22:20 INFO - mozversion application_version: 50.0a1 03:22:20 INFO - mozversion platform_buildid: 20160717010853 03:22:20 INFO - mozversion platform_changeset: 711963e8daa312ae06409f8ab5c06612cb0b8f7b 03:22:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:22:20 INFO - mozversion platform_version: 50.0a1 03:22:20 INFO - using testdate: 1468750940 03:22:20 INFO - actual date: 1468750940 03:22:20 INFO - starting webserver on 'localhost:49273' 03:22:20 INFO - SUITE-START | Running 2 tests 03:22:20 INFO - TEST-START | dromaeo_css 03:22:20 INFO - operating with platform_type : mac_ 03:22:20 INFO - Initialising browser for dromaeo_css test... 03:22:20 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpj8bn5d/profile http://localhost:49273/getInfo.html) 03:22:23 INFO - PROCESS | 1651 | JavaScript error: , line 0: uncaught exception: undefined 03:22:23 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 03:22:23 INFO - PROCESS | 1651 | colorDepth:24 03:22:23 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 03:22:23 INFO - PROCESS | 1651 | __metrics 03:22:23 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:22:23 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:22:23 INFO - TEST-INFO | 1651: exit 0 03:22:23 INFO - Browser initialized. 03:22:23 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:23 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpj8bn5d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:35 INFO - PROCESS | 1653 | RSS: Main: 245051392 03:22:35 INFO - PROCESS | 1653 | 03:23:27 INFO - PROCESS | 1653 | 1468751007462 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:25:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 03:25:39 INFO - PROCESS | 1653 | RSS: Main: 362516480 03:25:39 INFO - PROCESS | 1653 | 03:28:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 03:28:42 INFO - PROCESS | 1653 | RSS: Main: 385933312 03:28:42 INFO - PROCESS | 1653 | 03:31:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 03:31:45 INFO - PROCESS | 1653 | RSS: Main: 375230464 03:31:45 INFO - PROCESS | 1653 | 03:34:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 03:34:48 INFO - PROCESS | 1653 | RSS: Main: 369819648 03:34:48 INFO - PROCESS | 1653 | 03:37:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 03:37:52 INFO - PROCESS | 1653 | RSS: Main: 347537408 03:37:52 INFO - PROCESS | 1653 | 03:40:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 03:40:56 INFO - PROCESS | 1653 | RSS: Main: 406937600 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | __start_tp_report 03:40:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 03:40:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 03:40:56 INFO - PROCESS | 1653 | |i|pagename|runs| 03:40:56 INFO - PROCESS | 1653 | |0;dojo.html;3029.863656135474;3285.3922024425483;3391.304782519582;3419.880304189354;3451.8953934740875;5594.468525490072;5629.859253518664;5661.23573317602;5719.399463056377;5775.711214439271;5673.8581535461635;5720;5728.541716662668;5731.627444216123;5740.1102829061565;5171;5198;5225.817096401626;5245.160774276116;5302.390225124122;4370.431843860294;5251.291075704788;5273.4199238083975;5279.5512381447625;5310.7610157543;5174.586033117342;5200.557952574036;5224.425313215544;5229.267902493654;5277.340332458442;5275.129603615399;5283.709395983219;5299.258103865462;5308.6549374290935;5351.946480535223;5117.257997590365;5124.92312615311;5125.487451254845;5223.71269580173;5228.4510126436935;5653.717314134276;5658.122990936411;5700.344460387026;5724.141378793214;5727.169560413717;6267.811965641038;6282.2461304643275;6291.8741625167695;6300.653464059475;6311.842203944938;6109.205803245594;6199.690015499207;6283.026130949665;6352.237731472207;6379.425851673325;5870.5010074144175;5890.882182356372;5903.144044113666;5911.645301281894;5919.644821310735;6314.116023756677;6317.399847014526;6322.0516922462;6322.083297921776;6334.3032266450655;6018.578699491081;6083.4220749029655;6095;6119.258838874149;6204.007358822567;6082.908756368658;6304.148939893147;6304.590201636879;6306.684665766785;6314.842128946813;5476.698781566973;6159;6218.00511918091;6220.688965551795;6228.128062071313;6052.152698622196;6106.1146133811235;6118.9846826261555;6136.079588061826;6213.2233470816145;4925.7783399747095;4943.28322393251;4943.975280123576;4949.579285760678;4972.328735620645;3549.432090865449;4176.394422808737;4477.2164871147415;4509.346144808985;4528.592426681548;5745.253117094751;5747.827565173052;5754.251947246915;5766;5789.478946894716;6241.063840423882;6280.717367718464;6290.528210384148;6322.083297921776;6337.366263373626;4874.878128046827;5598.692071936001;5670.8015219467125;5692.117721753135;5775.306963164448;5434;5478.150886612581;5481.479259470344;5485.122380419114;5507.889842203133;6272.090546870678;6300.30696623371;6325.304216536178;6329.588576742497;6333.461655759312;6143.665407145256;6218.06728990655;6229.719662615103;6269.373062693694;6336.809895703136;6266.717997690023;6287.245530536365;6303.779367722108;6306.716197771019;6313.621182729051;5143.768530416215;6169.752702870635;6211.751529938751;6213.03697926822;6236;6032.517398608013;6148.569600127948;6178.814635560941;6248.0940263660605;6267.9373206267355;6079.679139076085;6102.115193297036;6107.144999699956;6117.644716737863;6117.888303246575;6054.243219597551;6098.878022439438;6102.176206212104;6107.511399087974;6141.7543298267565;5811.82564523065;5891.499222565958;5967.164596956516;5991.221141251609;6034.125051867542;6101.796556807712;6103.943827031843;6104.02736356799;6121.971262793429;6128.319797384489;5785.27684039495;5938.6436813791315;5963.671998039976;5965.612235204698;5973.701314934149 03:40:56 INFO - PROCESS | 1653 | |1;ext.html;483.87661146407663;489.8261117303357;507.3885967409271;511.88409267796203;531.1501597444087;30617.693823061763;30841.69158308416;32332.67667323326;32359.51460728088;41763.58236417635;41983.950401239985;44783;44790.55209447905;44810.32784508235;44912;9669.61321547139;9878.901210987906;9889.456079915602;9891.35706179096;9904.702858914245;8820.382573219877;8989.95505022474;9762.609495620167;9863.112319891208;12042.63872083839;12030;12275.69310767229;12278.447469863833;12299.815502767466;12319.384030798425;12137.939310303436;12165.087618428654;12194.878051219463;12199.39003049844;12208.267503949746;8728.650853965833;11737.354445505494;11980.580679676257;11995.700107497296;12040.939795301056;42353.57646423527;42460;42525.57474425247;42578;42583.57416425827;3475.8088305143206;3608.4226523756074;3721.144136848513;4039.596040395966;4067.186562687474;31853.044408667778;33045.50431743526;33307.66769329224;33464.33071338584;33645.6635433645;3258.6904244096772;3354.295597924439;3374.375740487993;3424.33225521021;3438.020164253176;493288;494923;495117;496090;496347;60660.393396066356;60704.392956069874;61155.6942215295;61239.69380153071;61244.69377653083;58512.829743404895;63562;63604.36395636077;63790.6810465954;63792;15175.317110730044;16423.096729679743;16467.917660411862;16471.25879335433;16519.83480165207;12815.743685126432;12871.613851584467;12872.806907896389;12872.871271287167;12895.935520322339;14230.359633816503;14604.561863144123;15854.44509442164;16269.10519921392;16287;6758.662066896635;7189.784306470814;7206.495545311778;7261.89107163393;7388.482806203514;922.7093465558462;960.5869476125193;979.314479864098;984.8621193032981;1030.8299130643509;29379.55930661042;29507;31017.379652406824;31796;31890.88381906622;1085.5006696919347;1091.1270983213556;1148.9885101148882;1165.2367699157066;1245.5578269714122;15875.968062075928;15924.92037539805;16047.358105675876;16237.51287461378;16280.591022804974;188723.05638471994;192905.03547482172;193479;193646;193677;52730;61044.69477652583;61374.386256136866;61567.46081348002;61968.380316196264;1414.9646258843406;1426.137187001849;1443.444273954535;1445.2268036600578;1447.7104579084014;31538;32318.515222272155;32376;32627.18432039218;33222.66777332292;52011.479885200664;52517.474825252786;52539.47460525499;52594;52609;591835;608711;611178;611218;613234;33663.83168084242;40398.79800600978;41209;41322.93385330661;41411.585884141976;49440.6480630166;49954;50024;50454.747726261136;50849.74575127249;32646.34707305888;39470.02649867553;40722.18555628812;41103;42225.31098756138;33142.33715325632;35344;36105.638943610225;40429;40713.18573628452;39067.21865562616;39636.2072758549;39828.20343593054;39847.40228896511;39929;34699.74164338816;35085;35441.268953951876;36005.45991810073;37512;23215.53568928646;24644.630330545413;26111;26216;26273.868630656725 03:40:56 INFO - PROCESS | 1653 | |2;jquery.html;341.7556447140294;367.5993167447485;392.54660866698964;396.1519511478949;418.43093392985537;15881.364745410185;15993.28030238639;21240.25659101928;21240.83175425353;21359.35921922341;20927.68608470874;21500.247491337766;21579.568408631818;21908.8904555477;21909.890450547766;5352.973235133819;5936.584439089265;6208.50331973441;6216.036514340262;6245.313015568284;6153.292371377282;6182.752689892399;6188.659623720693;6219.160413344209;6225.252969643627;5911.142884281777;6178.907316390258;6191;6201.1008403781425;6202.875942481171;5910.586258961875;6112.205413296265;6193;6197.163382943312;6208.906866397007;5385.515303622654;5399.83800485986;5414.83755487336;5416.0792665247;5417.729113544307;21473.24843630481;25670.743292567022;25723.485530289476;25848;26401.3399665008;2102.98948505258;2274.4200228941513;2327.2320134355623;2333.8949747261413;2548.5157820014138;10231.130353919925;10434.634787782392;10531.315464494783;11367.829482557769;16322.347106115742;1707.2488105233658;1795.5331613780381;1890.0738638067385;1896.5732710140246;1915.4445210888964;83167;103943.48028259886;104480;117464.82535174624;125272;11493.42532873353;11809.645710628523;11823.940880295544;11857.644270671894;11982.520699171935;14732.55802325932;14856.702865942621;14875.776863347059;14880.255987200597;14892.70214595702;8566.61450234741;8610.407074691202;8623.655053797904;8672.523011234316;8676.696315628922;8523;8547.615357308936;8551.230389264996;8640.092790257058;8684.78288042804;3889.5138107736534;4616.422947131608;4621.722696638212;4681.133990211822;4690.6012988895645;1698.1725427163635;1815.2285278756476;1907.36103405358;1939.7381353517094;1944.3389247655864;389.6415297925915;401.9718619696593;714.4888560694095;999.7000899730052;1052.957881684724;10318;11480.368579728029;12136.029117670565;12276.693082673004;12477.93761031189;433.68124428544996;445.5989609351545;449.03009499480964;451.4650139584603;461.4347424405077;6117.816465506042;6191.752329906753;6223.564350495421;6268.1851359323;6289.088082228142;618450.8293985972;619730;627371;634039.4470414737;639019;82987.5850620743;108762.48924519894;108955;142490;148827;946.6592026870321;980.9901900980899;990.0396615283164;990.4107056301489;992.0773680477225;19710.343390441125;20148.093335799633;20149.49626259355;20160;20160.17233985292;47335.053298934516;47986.760066199444;51165.465036048976;52044.479555203965;55027.724861377035;76220.61889690516;78251.60874195592;80007.59996199982;81785.77321340065;82300;11166.665000050012;12021.939890300493;12074.818877716665;12128;12524;13528.188308701509;13967.022308438312;13984.580462586138;14091.295435227992;14124.576262712135;10608.946955265174;11174.776504470028;11662.591809286634;11993.220440671328;12109.636710898687;11457.312561246019;11757.176997610086;11849.407529623657;11923.284602923852;12137.393130343271;11434.08527317796;11744.17790754639;11926.403679815801;12002.879971200176;12104.576339828063;10439.94780026095;12243.510259589517;12355.93822030884;12465.376731163224;12487.188332758342;11753.647390578297;11783.998360139483;11852.762944740884;11957.880421196025;12089.274643521418 03:40:56 INFO - PROCESS | 1653 | |3;mootools.html;1493.6639256167364;1537.3543111893005;1755.271562301645;1914.5022294203495;1918.175184670591;7469.925300746991;8381.748547543568;8620.956895215531;8806.295496360292;8850.336224783143;7207.459440541954;7933.76198714038;8327.791805204874;8571.871421928661;8624.008239052511;2347.835651504395;2377.72656144543;2378.262738551046;2378.357843382286;2383.249276477904;2360;2380.3811009137576;2405.458771776354;2408.7233766103905;2415.9758402416014;2101.359085478928;2166.5558560495137;2253.2226381898217;2322.094383190557;2325.5930212212793;2350.330155905567;2354.9646755298513;2379.238643634038;2379.8929048192786;2387.0571124405938;2348.9177878774162;2360.0677732295817;2362.9172978945826;2364.598018336887;2365.420471984358;8605.311575074042;8750.824983500357;8752.387332886701;8753.949526056851;8766.167214114712;5171.715555644438;5329.840104796862;5331.600129990227;5345.438728933484;5347.598930080259;3595.496630471736;3602.441621548664;3661.1579336752698;3672.944905826415;3675.44868269759;1097.9231453798238;1100.9889901100967;1102.3551222534827;1119.8880111988817;1120.1263014848432;11509.366984815832;11520.539178432853;11527.48126334317;11549.595764148215;11550.88449115515;3923.8228531440454;4495.57292057254;4647.117917243521;4729.716217026989;4763.237881938873;9701.417914925127;9710.6115755369;9711.708648740549;9714.805703886024;9754.975727548504;5287.206918962186;5288.0745869472685;5303.178007408857;5304.230886521433;5315.707636079976;4723.527647235249;4861.343718598015;4869.1722407190155;4871.269309603517;4873.585745211697;3841.5582208045876;3870.41943708446;3906.628870257378;4001.6398524132387;4017.457643218128;1176.2178151529138;1218.993905030487;1267.182667179664;1285.2609749393948;1299.1620404838825;721.6860665610476;767.1561282589107;913.4701872913694;925.3615005646217;947.5262368815593;5165.457626949116;5737.250138707735;5951.851203719942;5959.404059594006;6092.45586516268;938.2869019545195;954.6229239450378;955.3360414511986;959.6017652674202;987.3039507147444;3944.60553944609;3988.3818008208773;4063.654589359878;4076.6331030207425;4085.7957102144774;9851.310408271353;10699.946500267604;10712.625058122929;10717.83923241152;10742.3017503862;8719.389642725075;9131;9279.932807727062;10598.947005264925;10689.625863094754;7335.926640733631;8199.877001844978;8228.382871284677;8237.258646721824;8252.504849709037;5795.449432303924;5927.911081333869;5957.106434034929;5992.940070599238;6015.097735339734;9499.840523084295;9832.967538408364;9951.253655975686;9952.55213515379;9960.302778805413;7966.482178658369;9841.950790246003;10168.084872361524;10220.540075696463;10221.386716797017;7255.2019277880445;7265.237150099163;7346.5592064476305;7349.4855360124275;7357.1907090218965;8090.6359213836695;8103.108658047492;8143.714969976022;8175;8177.877331839908;6008.279006519071;7043.2604576518725;7324.96337518309;7432.665530051053;7545.094588649397;7239.927600723925;7262.9273707262255;7464.701411943462;7473.813154671177;7501.062367204099;6803.625800581016;6808.761693340709;6883;6884.965575172092;6886.2769409213315;4843;4966.155753521838;4979.278004689372;5054.69671819692;5057.418396884405;5013.24801279811;5041.823536176363;5066.746662666926;5099.643024988363;5155.587552995827 03:40:56 INFO - PROCESS | 1653 | |4;prototype.html;306.59529421164063;317.477749102726;320.0877499127486;324.30922135850636;325.60926887734723;5498.477644623759;5609.429359779263;5609.831705048845;5665.490105890469;5685.8578535536635;5454.945450545493;5491.972540137293;5543.695096769676;5577.852526777189;5592.552595792337;3442.5180474733556;3872.419137129432;4029.576894426087;4076.7961601919787;4112.568180341066;3455.5507783988046;3694.8522059117604;4165.916681666365;4178.56125106864;4209.936850947238;4137;4144.647704945083;4179.519355274137;4201.76890271035;4211.136716973013;3978.0850404407147;4055.2497787909333;4218.367244913287;4230.371307046794;4231.534531201565;2964.880110720845;3372.730181585467;3463.3592785334795;3550.8934731958084;3598.5142005829402;5282.336064139784;5291.417944026154;5298.311219541473;5301.893962120775;5334.0665383557725;3661.6521430464063;3969.8412063517426;4543.31850222466;4591.6097131743845;4759.025608994379;5180.430152683201;5742.109972954198;5752.325168796529;5766.88466230673;5789.247397838254;3467.583889933199;3629.6551827576336;3769.227308401771;3974.2647610192207;3981.3278423062384;4245.830166793325;5619.7471113799975;6045.939540604581;6125.540584456184;6135.938640613582;3425.2293234022645;3711.6659500645073;4090.406891000788;4102.3026085565525;4120.340745480708;3423.3666771647327;3724.7951362675312;4003;4071.5114186297833;4217.97891010543;4377.868663940087;4590.495045544987;4823.903521929542;4848.684835485682;4859.586935110555;5552.501923787988;5554.972225138929;5853.64878107315;5866.67733274674;5890.734916928748;1202.4889421995792;1278.6547632139457;1328.35574746248;1340.9329533523285;1423.565812427199;791.8733002719537;839.9244068033906;876.8334016536837;885.9202671759573;888.1828717579842;3936.6653834423823;4280.743155410685;4291.09510345584;4408.170609197211;4427.29163333865;4269;4978.5768209701855;5010.023045506165;5023.296738456619;5056.797728090909;4052.473178486837;4374.978125109417;4425.026494171278;4513.435820522434;4546.477155127202;2422.3770074050076;2632.499825033276;2665.7867370610693;2674.8528830914097;2678.076063758;4350.695451318377;4744;4857.967886654069;4971.080350135237;5117.4903403496055;4708.399144290957;4782.689125206851;4796.043264306961;5137.845864624067;5764.424725119113;5243.554297884646;5928.199693041471;6248.968755156195;6255.56211065221;6257.624542527463;5854.707264636836;6001.129836173817;6039.667818270037;6040.728167232573;6067.423594758491;4731.952680473151;5112.923306150336;5123.5644970177955;5845.006348920779;5912.881742365214;4904.8773780655765;5094.770735316845;5901.29184497863;6169.413905678954;6172.796304720537;3371.5165327255927;3981.4425980362194;4056.20903923732;4056.9391459127546;4107.938380924348;5363.731813409392;5814.505767009851;5830.562707797017;5916.911246331222;6029.909551356645;5705.971470142788;5784.566157538117;5785.56608254391;5819.418058194146;5858.970705146447;5480.698561579152;5800.214822771315;5820.243368362086;5825.388334225015;5873.295204575478;5330.8933821324135;5706;5724.771009159587;5743.052396354722;5757.8560535986935;5337;5431.402545719889;5764.538836893123;5844.6785426801935;5883;5394.676319420847;5576.86057848557;5682.499700132094;5686.578355411148;5737.512311453574 03:40:56 INFO - PROCESS | 1653 | |5;yui.html;632.7374139732011;755.5466719968016;807.2895851650546;817.5503473089799;851.2423942691006;15491.767623485643;16232.594185145363;16236.594085147877;16245.26896289667;17145.05702186379;15900.125493097876;16775.161241937916;16791.83208167918;16803;16809.15954202291;715.8389362393472;739.5858319341186;885.614757580453;920.6409500294891;922.7416323429417;890.132121181849;919.6873063158524;919.7516670498961;924.9676261330854;931.4364809290349;975.0444564326964;976.0434773921561;976.7460460280342;979.3487330924927;983.0464449483989;740.152525358467;765.8659734546487;768.8616049111158;961.5432669481975;962.5957098018814;572.879695263995;669.9162604674416;695.2560759986816;721.2967356827052;722.8843385058366;13236.676332366686;14971.176585287805;15369.846301536953;15408.614784630361;15417;14222.359993800304;14363.066400683922;14428.278586070655;14486.637834054232;14557.344919478648;12977.675558111123;13199.40602672882;13239.338033098404;13241.867581324159;13638.386272617756;3897.649211570972;3925.60743925608;4020.979895100506;4022.557518672944;4040.030392705759;120818.99999999913;123716;124076;124977;125081.99999999908;11421;11592.47833847462;11630.76738465226;11888.821667674993;12028.977536909286;23326.416839579146;23344.7665523346;23368.88315558445;23403.88298058499;23411.882940585187;7904.683812647545;8017.559034253172;8063.758087257392;8086.959565202136;8236.258736713724;4325.307950727932;4412.580804823537;4425.380446737459;4602.7008244464005;4720.33915251865;3416.538767266437;3541.220931395089;3547.184147646056;3551.1477245460906;3555.8577656893954;606.5663050918527;663.4161937494973;672.3444641474621;673.2829536543566;680.8706345794283;633.2622494793513;645.6112384178465;647.5726020826232;652.5236577298599;659.1859054068221;7838.844317812647;8699.130086991378;8903.910960890309;9120.635174593075;9179.77050573741;560.9691466969274;611.3886113886114;617.9505639548826;630.9842253943688;631.520044765972;4381.84663536781;4411.139827733561;4427.313766366218;4501.932471012938;4505.549445055533;145936.40635936533;148406;151368;152486;157729;125022.74977250292;126176.73823261833;127047;129551;130845;93958;99280.50359748155;100123.99752005065;101720.49139754254;109886;9835.262355323237;9861.265094537477;10064.446455444728;10106.747331316776;10218.846717299099;50134;63008.36991630209;64074.679626601566;65631.44534348654;65637;92834;95899;97240;98491.01508984818;99993.50003250228;13361.53234636783;13378.331083445983;13399.12905661129;13958.790618140532;14039;14979.475718349806;14991.850081499482;15137.167455790037;15544.300506477462;15739.976901501825;14113.576592702235;14163.291835407983;14183.432662693376;14314.355853986388;14623.009077959136;13785.379657915217;14185.007049506848;14341.713165736835;14528.636784080483;14661.560153195422;13650.453981840616;13888.166709997433;13916.652083697989;13930.303484825921;13936.79094813599;11327.943360283145;11434.94282528582;11502.48238829238;11713.472893719632;11829.585964491202;8928.553572321229;11235.719107022063;11365.723162748458;11698.94150529242;11708 03:40:56 INFO - PROCESS | 1653 | __end_tp_report 03:40:56 INFO - PROCESS | 1653 | __start_cc_report 03:40:56 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1106 03:40:56 INFO - PROCESS | 1653 | __end_cc_report 03:40:56 INFO - PROCESS | 1653 | __startTimestamp1468752056115__endTimestamp 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | ------- Summary: start ------- 03:40:56 INFO - PROCESS | 1653 | Number of tests: 6 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5733.23 Median:5965.61 stddev:666.63 (11.2%) stddev-sans-first:634.04 03:40:56 INFO - PROCESS | 1653 | Values: 3029.9 3285.4 3391.3 3419.9 3451.9 5594.5 5629.9 5661.2 5719.4 5775.7 5673.9 5720.0 5728.5 5731.6 5740.1 5171.0 5198.0 5225.8 5245.2 5302.4 4370.4 5251.3 5273.4 5279.6 5310.8 5174.6 5200.6 5224.4 5229.3 5277.3 5275.1 5283.7 5299.3 5308.7 5351.9 5117.3 5124.9 5125.5 5223.7 5228.5 5653.7 5658.1 5700.3 5724.1 5727.2 6267.8 6282.2 6291.9 6300.7 6311.8 6109.2 6199.7 6283.0 6352.2 6379.4 5870.5 5890.9 5903.1 5911.6 5919.6 6314.1 6317.4 6322.1 6322.1 6334.3 6018.6 6083.4 6095.0 6119.3 6204.0 6082.9 6304.1 6304.6 6306.7 6314.8 5476.7 6159.0 6218.0 6220.7 6228.1 6052.2 6106.1 6119.0 6136.1 6213.2 4925.8 4943.3 4944.0 4949.6 4972.3 3549.4 4176.4 4477.2 4509.3 4528.6 5745.3 5747.8 5754.3 5766.0 5789.5 6241.1 6280.7 6290.5 6322.1 6337.4 4874.9 5598.7 5670.8 5692.1 5775.3 5434.0 5478.2 5481.5 5485.1 5507.9 6272.1 6300.3 6325.3 6329.6 6333.5 6143.7 6218.1 6229.7 6269.4 6336.8 6266.7 6287.2 6303.8 6306.7 6313.6 5143.8 6169.8 6211.8 6213.0 6236.0 6032.5 6148.6 6178.8 6248.1 6267.9 6079.7 6102.1 6107.1 6117.6 6117.9 6054.2 6098.9 6102.2 6107.5 6141.8 5811.8 5891.5 5967.2 5991.2 6034.1 6101.8 6103.9 6104.0 6122.0 6128.3 5785.3 5938.6 5963.7 5965.6 5973.7 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:59681.16 Median:19913.39 stddev:124727.87 (626.4%) stddev-sans-first:124998.58 03:40:56 INFO - PROCESS | 1653 | Values: 483.9 489.8 507.4 511.9 531.2 30617.7 30841.7 32332.7 32359.5 41763.6 41984.0 44783.0 44790.6 44810.3 44912.0 9669.6 9878.9 9889.5 9891.4 9904.7 8820.4 8990.0 9762.6 9863.1 12042.6 12030.0 12275.7 12278.4 12299.8 12319.4 12137.9 12165.1 12194.9 12199.4 12208.3 8728.7 11737.4 11980.6 11995.7 12040.9 42353.6 42460.0 42525.6 42578.0 42583.6 3475.8 3608.4 3721.1 4039.6 4067.2 31853.0 33045.5 33307.7 33464.3 33645.7 3258.7 3354.3 3374.4 3424.3 3438.0 493288.0 494923.0 495117.0 496090.0 496347.0 60660.4 60704.4 61155.7 61239.7 61244.7 58512.8 63562.0 63604.4 63790.7 63792.0 15175.3 16423.1 16467.9 16471.3 16519.8 12815.7 12871.6 12872.8 12872.9 12895.9 14230.4 14604.6 15854.4 16269.1 16287.0 6758.7 7189.8 7206.5 7261.9 7388.5 922.7 960.6 979.3 984.9 1030.8 29379.6 29507.0 31017.4 31796.0 31890.9 1085.5 1091.1 1149.0 1165.2 1245.6 15876.0 15924.9 16047.4 16237.5 16280.6 188723.1 192905.0 193479.0 193646.0 193677.0 52730.0 61044.7 61374.4 61567.5 61968.4 1415.0 1426.1 1443.4 1445.2 1447.7 31538.0 32318.5 32376.0 32627.2 33222.7 52011.5 52517.5 52539.5 52594.0 52609.0 591835.0 608711.0 611178.0 611218.0 613234.0 33663.8 40398.8 41209.0 41322.9 41411.6 49440.6 49954.0 50024.0 50454.7 50849.7 32646.3 39470.0 40722.2 41103.0 42225.3 33142.3 35344.0 36105.6 40429.0 40713.2 39067.2 39636.2 39828.2 39847.4 39929.0 34699.7 35085.0 35441.3 36005.5 37512.0 23215.5 24644.6 26111.0 26216.0 26273.9 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35296.93 Median:12092.16 stddev:104155.20 (861.3%) stddev-sans-first:104414.31 03:40:56 INFO - PROCESS | 1653 | Values: 341.8 367.6 392.5 396.2 418.4 15881.4 15993.3 21240.3 21240.8 21359.4 20927.7 21500.2 21579.6 21908.9 21909.9 5353.0 5936.6 6208.5 6216.0 6245.3 6153.3 6182.8 6188.7 6219.2 6225.3 5911.1 6178.9 6191.0 6201.1 6202.9 5910.6 6112.2 6193.0 6197.2 6208.9 5385.5 5399.8 5414.8 5416.1 5417.7 21473.2 25670.7 25723.5 25848.0 26401.3 2103.0 2274.4 2327.2 2333.9 2548.5 10231.1 10434.6 10531.3 11367.8 16322.3 1707.2 1795.5 1890.1 1896.6 1915.4 83167.0 103943.5 104480.0 117464.8 125272.0 11493.4 11809.6 11823.9 11857.6 11982.5 14732.6 14856.7 14875.8 14880.3 14892.7 8566.6 8610.4 8623.7 8672.5 8676.7 8523.0 8547.6 8551.2 8640.1 8684.8 3889.5 4616.4 4621.7 4681.1 4690.6 1698.2 1815.2 1907.4 1939.7 1944.3 389.6 402.0 714.5 999.7 1053.0 10318.0 11480.4 12136.0 12276.7 12477.9 433.7 445.6 449.0 451.5 461.4 6117.8 6191.8 6223.6 6268.2 6289.1 618450.8 619730.0 627371.0 634039.4 639019.0 82987.6 108762.5 108955.0 142490.0 148827.0 946.7 981.0 990.0 990.4 992.1 19710.3 20148.1 20149.5 20160.0 20160.2 47335.1 47986.8 51165.5 52044.5 55027.7 76220.6 78251.6 80007.6 81785.8 82300.0 11166.7 12021.9 12074.8 12128.0 12524.0 13528.2 13967.0 13984.6 14091.3 14124.6 10608.9 11174.8 11662.6 11993.2 12109.6 11457.3 11757.2 11849.4 11923.3 12137.4 11434.1 11744.2 11926.4 12002.9 12104.6 10439.9 12243.5 12355.9 12465.4 12487.2 11753.6 11784.0 11852.8 11957.9 12089.3 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5612.83 Median:5229.46 stddev:3082.72 (58.9%) stddev-sans-first:3075.83 03:40:56 INFO - PROCESS | 1653 | Values: 1493.7 1537.4 1755.3 1914.5 1918.2 7469.9 8381.7 8621.0 8806.3 8850.3 7207.5 7933.8 8327.8 8571.9 8624.0 2347.8 2377.7 2378.3 2378.4 2383.2 2360.0 2380.4 2405.5 2408.7 2416.0 2101.4 2166.6 2253.2 2322.1 2325.6 2350.3 2355.0 2379.2 2379.9 2387.1 2348.9 2360.1 2362.9 2364.6 2365.4 8605.3 8750.8 8752.4 8753.9 8766.2 5171.7 5329.8 5331.6 5345.4 5347.6 3595.5 3602.4 3661.2 3672.9 3675.4 1097.9 1101.0 1102.4 1119.9 1120.1 11509.4 11520.5 11527.5 11549.6 11550.9 3923.8 4495.6 4647.1 4729.7 4763.2 9701.4 9710.6 9711.7 9714.8 9755.0 5287.2 5288.1 5303.2 5304.2 5315.7 4723.5 4861.3 4869.2 4871.3 4873.6 3841.6 3870.4 3906.6 4001.6 4017.5 1176.2 1219.0 1267.2 1285.3 1299.2 721.7 767.2 913.5 925.4 947.5 5165.5 5737.3 5951.9 5959.4 6092.5 938.3 954.6 955.3 959.6 987.3 3944.6 3988.4 4063.7 4076.6 4085.8 9851.3 10699.9 10712.6 10717.8 10742.3 8719.4 9131.0 9279.9 10598.9 10689.6 7335.9 8199.9 8228.4 8237.3 8252.5 5795.4 5927.9 5957.1 5992.9 6015.1 9499.8 9833.0 9951.3 9952.6 9960.3 7966.5 9842.0 10168.1 10220.5 10221.4 7255.2 7265.2 7346.6 7349.5 7357.2 8090.6 8103.1 8143.7 8175.0 8177.9 6008.3 7043.3 7325.0 7432.7 7545.1 7239.9 7262.9 7464.7 7473.8 7501.1 6803.6 6808.8 6883.0 6885.0 6886.3 4843.0 4966.2 4979.3 5054.7 5057.4 5013.2 5041.8 5066.7 5099.6 5155.6 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4533.43 Median:4974.83 stddev:1435.58 (28.9%) stddev-sans-first:1404.11 03:40:56 INFO - PROCESS | 1653 | Values: 306.6 317.5 320.1 324.3 325.6 5498.5 5609.4 5609.8 5665.5 5685.9 5454.9 5492.0 5543.7 5577.9 5592.6 3442.5 3872.4 4029.6 4076.8 4112.6 3455.6 3694.9 4165.9 4178.6 4209.9 4137.0 4144.6 4179.5 4201.8 4211.1 3978.1 4055.2 4218.4 4230.4 4231.5 2964.9 3372.7 3463.4 3550.9 3598.5 5282.3 5291.4 5298.3 5301.9 5334.1 3661.7 3969.8 4543.3 4591.6 4759.0 5180.4 5742.1 5752.3 5766.9 5789.2 3467.6 3629.7 3769.2 3974.3 3981.3 4245.8 5619.7 6045.9 6125.5 6135.9 3425.2 3711.7 4090.4 4102.3 4120.3 3423.4 3724.8 4003.0 4071.5 4218.0 4377.9 4590.5 4823.9 4848.7 4859.6 5552.5 5555.0 5853.6 5866.7 5890.7 1202.5 1278.7 1328.4 1340.9 1423.6 791.9 839.9 876.8 885.9 888.2 3936.7 4280.7 4291.1 4408.2 4427.3 4269.0 4978.6 5010.0 5023.3 5056.8 4052.5 4375.0 4425.0 4513.4 4546.5 2422.4 2632.5 2665.8 2674.9 2678.1 4350.7 4744.0 4858.0 4971.1 5117.5 4708.4 4782.7 4796.0 5137.8 5764.4 5243.6 5928.2 6249.0 6255.6 6257.6 5854.7 6001.1 6039.7 6040.7 6067.4 4732.0 5112.9 5123.6 5845.0 5912.9 4904.9 5094.8 5901.3 6169.4 6172.8 3371.5 3981.4 4056.2 4056.9 4107.9 5363.7 5814.5 5830.6 5916.9 6029.9 5706.0 5784.6 5785.6 5819.4 5859.0 5480.7 5800.2 5820.2 5825.4 5873.3 5330.9 5706.0 5724.8 5743.1 5757.9 5337.0 5431.4 5764.5 5844.7 5883.0 5394.7 5576.9 5682.5 5686.6 5737.5 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25468.43 Median:3478.88 stddev:40263.31 (1157.4%) stddev-sans-first:40333.08 03:40:56 INFO - PROCESS | 1653 | Values: 632.7 755.5 807.3 817.6 851.2 15491.8 16232.6 16236.6 16245.3 17145.1 15900.1 16775.2 16791.8 16803.0 16809.2 715.8 739.6 885.6 920.6 922.7 890.1 919.7 919.8 925.0 931.4 975.0 976.0 976.7 979.3 983.0 740.2 765.9 768.9 961.5 962.6 572.9 669.9 695.3 721.3 722.9 13236.7 14971.2 15369.8 15408.6 15417.0 14222.4 14363.1 14428.3 14486.6 14557.3 12977.7 13199.4 13239.3 13241.9 13638.4 3897.6 3925.6 4021.0 4022.6 4040.0 120819.0 123716.0 124076.0 124977.0 125082.0 11421.0 11592.5 11630.8 11888.8 12029.0 23326.4 23344.8 23368.9 23403.9 23411.9 7904.7 8017.6 8063.8 8087.0 8236.3 4325.3 4412.6 4425.4 4602.7 4720.3 3416.5 3541.2 3547.2 3551.1 3555.9 606.6 663.4 672.3 673.3 680.9 633.3 645.6 647.6 652.5 659.2 7838.8 8699.1 8903.9 9120.6 9179.8 561.0 611.4 618.0 631.0 631.5 4381.8 4411.1 4427.3 4501.9 4505.5 145936.4 148406.0 151368.0 152486.0 157729.0 125022.7 126176.7 127047.0 129551.0 130845.0 93958.0 99280.5 100124.0 101720.5 109886.0 9835.3 9861.3 10064.4 10106.7 10218.8 50134.0 63008.4 64074.7 65631.4 65637.0 92834.0 95899.0 97240.0 98491.0 99993.5 13361.5 13378.3 13399.1 13958.8 14039.0 14979.5 14991.9 15137.2 15544.3 15740.0 14113.6 14163.3 14183.4 14314.4 14623.0 13785.4 14185.0 14341.7 14528.6 14661.6 13650.5 13888.2 13916.7 13930.3 13936.8 11327.9 11434.9 11502.5 11713.5 11829.6 8928.6 11235.7 11365.7 11698.9 11708.0 03:40:56 INFO - PROCESS | 1653 | -------- Summary: end -------- 03:40:56 INFO - PROCESS | 1653 | 03:40:56 INFO - TEST-INFO | 1653: exit 0 03:40:56 INFO - TEST-OK | dromaeo_css | took 1116060ms 03:40:56 INFO - TEST-START | kraken 03:40:56 INFO - operating with platform_type : mac_ 03:40:56 INFO - Initialising browser for kraken test... 03:40:56 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpIAd1nj/profile http://localhost:49273/getInfo.html) 03:40:58 INFO - PROCESS | 1685 | JavaScript error: , line 0: uncaught exception: undefined 03:40:58 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 03:40:58 INFO - PROCESS | 1685 | colorDepth:24 03:40:58 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 03:40:58 INFO - PROCESS | 1685 | __metrics 03:40:58 INFO - PROCESS | 1685 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:40:58 INFO - PROCESS | 1685 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:40:59 INFO - TEST-INFO | 1685: exit 0 03:40:59 INFO - Browser initialized. 03:40:59 INFO - Running cycle 1/1 for kraken test... 03:40:59 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpIAd1nj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:11 INFO - PROCESS | 1686 | RSS: Main: 245272576 03:41:11 INFO - PROCESS | 1686 | 03:41:14 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:15 INFO - PROCESS | 1686 | RSS: Main: 295501824 03:41:15 INFO - PROCESS | 1686 | 03:41:17 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 03:41:18 INFO - PROCESS | 1686 | RSS: Main: 305500160 03:41:18 INFO - PROCESS | 1686 | 03:41:21 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 03:41:22 INFO - PROCESS | 1686 | RSS: Main: 389459968 03:41:22 INFO - PROCESS | 1686 | 03:41:24 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 03:41:24 INFO - PROCESS | 1686 | RSS: Main: 291790848 03:41:24 INFO - PROCESS | 1686 | 03:41:27 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:27 INFO - PROCESS | 1686 | RSS: Main: 296058880 03:41:27 INFO - PROCESS | 1686 | 03:41:30 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:31 INFO - PROCESS | 1686 | RSS: Main: 333803520 03:41:31 INFO - PROCESS | 1686 | 03:41:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:34 INFO - PROCESS | 1686 | RSS: Main: 333393920 03:41:34 INFO - PROCESS | 1686 | 03:41:37 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 03:41:37 INFO - PROCESS | 1686 | RSS: Main: 334282752 03:41:37 INFO - PROCESS | 1686 | 03:41:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:40 INFO - PROCESS | 1686 | RSS: Main: 312434688 03:41:40 INFO - PROCESS | 1686 | 03:41:42 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:42 INFO - PROCESS | 1686 | RSS: Main: 319639552 03:41:42 INFO - PROCESS | 1686 | 03:41:44 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:45 INFO - PROCESS | 1686 | RSS: Main: 316887040 03:41:45 INFO - PROCESS | 1686 | 03:41:48 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:48 INFO - PROCESS | 1686 | RSS: Main: 324329472 03:41:48 INFO - PROCESS | 1686 | 03:41:51 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:52 INFO - PROCESS | 1686 | RSS: Main: 329011200 03:41:52 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 03:41:54 INFO - PROCESS | 1686 | RSS: Main: 372097024 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | __start_tp_report 03:41:54 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 03:41:54 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 03:41:54 INFO - PROCESS | 1686 | |i|pagename|runs| 03:41:54 INFO - PROCESS | 1686 | |0;ai-astar;107;113;114;113;115;113;113;113;113;113 03:41:54 INFO - PROCESS | 1686 | |1;audio-beat-detection;142;108;108;157;107;107;130;107;139;104 03:41:54 INFO - PROCESS | 1686 | |2;audio-dft;184;183;181;185;185;183;180;183;183;185 03:41:54 INFO - PROCESS | 1686 | |3;audio-fft;79;57;57;104;58;57;65;62;80;58 03:41:54 INFO - PROCESS | 1686 | |4;audio-oscillator;88;69;91;76;78;69;81;71;89;134 03:41:54 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;88;86;89;85;88;85;89;87;134;118 03:41:54 INFO - PROCESS | 1686 | |6;imaging-darkroom;94;94;94;94;93;94;95;95;94;93 03:41:54 INFO - PROCESS | 1686 | |7;imaging-desaturate;87;86;86;87;83;84;83;84;87;85 03:41:54 INFO - PROCESS | 1686 | |8;json-parse-financial;54;53;52;53;53;53;54;54;52;53 03:41:54 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;71;76;78;69;73;73;70;72;71;73 03:41:54 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;82;82;85;83;84;86;100;100;81;82 03:41:54 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;137;137;135;136;136;135;131;132;176;136 03:41:54 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;160;180;229;171;165;171;171;172;169;170 03:41:54 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;67;68;68;69;69;68;67;67;67;64 03:41:54 INFO - PROCESS | 1686 | __end_tp_report 03:41:54 INFO - PROCESS | 1686 | __start_cc_report 03:41:54 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,2025 03:41:54 INFO - PROCESS | 1686 | __end_cc_report 03:41:54 INFO - PROCESS | 1686 | __startTimestamp1468752114426__endTimestamp 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | ------- Summary: start ------- 03:41:54 INFO - PROCESS | 1686 | Number of tests: 14 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:2.11 (1.9%) stddev-sans-first:0.71 03:41:54 INFO - PROCESS | 1686 | Values: 107.0 113.0 114.0 113.0 115.0 113.0 113.0 113.0 113.0 113.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:120.90 Median:119.00 stddev:19.31 (16.2%) stddev-sans-first:18.92 03:41:54 INFO - PROCESS | 1686 | Values: 142.0 108.0 108.0 157.0 107.0 107.0 130.0 107.0 139.0 104.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.69 (0.9%) stddev-sans-first:1.76 03:41:54 INFO - PROCESS | 1686 | Values: 184.0 183.0 181.0 185.0 185.0 183.0 180.0 183.0 183.0 185.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:67.70 Median:60.00 stddev:15.52 (25.9%) stddev-sans-first:15.91 03:41:54 INFO - PROCESS | 1686 | Values: 79.0 57.0 57.0 104.0 58.0 57.0 65.0 62.0 80.0 58.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:79.50 stddev:19.19 (24.1%) stddev-sans-first:20.31 03:41:54 INFO - PROCESS | 1686 | Values: 88.0 69.0 91.0 76.0 78.0 69.0 81.0 71.0 89.0 134.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:87.50 stddev:16.88 (19.3%) stddev-sans-first:17.72 03:41:54 INFO - PROCESS | 1686 | Values: 88.0 86.0 89.0 85.0 88.0 85.0 89.0 87.0 134.0 118.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 03:41:54 INFO - PROCESS | 1686 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 94.0 93.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.62 (1.9%) stddev-sans-first:1.58 03:41:54 INFO - PROCESS | 1686 | Values: 87.0 86.0 86.0 87.0 83.0 84.0 83.0 84.0 87.0 85.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 03:41:54 INFO - PROCESS | 1686 | Values: 54.0 53.0 52.0 53.0 53.0 53.0 54.0 54.0 52.0 53.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:73.00 stddev:2.72 (3.7%) stddev-sans-first:2.82 03:41:54 INFO - PROCESS | 1686 | Values: 71.0 76.0 78.0 69.0 73.0 73.0 70.0 72.0 71.0 73.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:82.50 stddev:7.28 (8.8%) stddev-sans-first:7.53 03:41:54 INFO - PROCESS | 1686 | Values: 82.0 82.0 85.0 83.0 84.0 86.0 100.0 100.0 81.0 82.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:136.00 stddev:13.12 (9.6%) stddev-sans-first:13.89 03:41:54 INFO - PROCESS | 1686 | Values: 137.0 137.0 135.0 136.0 136.0 135.0 131.0 132.0 176.0 136.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:171.00 stddev:19.37 (11.3%) stddev-sans-first:19.69 03:41:54 INFO - PROCESS | 1686 | Values: 160.0 180.0 229.0 171.0 165.0 171.0 171.0 172.0 169.0 170.0 03:41:54 INFO - PROCESS | 1686 | 03:41:54 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.43 (2.1%) stddev-sans-first:1.51 03:41:54 INFO - PROCESS | 1686 | Values: 67.0 68.0 68.0 69.0 69.0 68.0 67.0 67.0 67.0 64.0 03:41:54 INFO - PROCESS | 1686 | -------- Summary: end -------- 03:41:54 INFO - PROCESS | 1686 | 03:41:55 INFO - TEST-INFO | 1686: exit 0 03:41:55 INFO - TEST-OK | kraken | took 58565ms 03:41:55 INFO - SUITE-END | took 1174s 03:41:55 INFO - Completed test suite (00:19:34) 03:41:55 INFO - javascript benchmark 03:41:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3029.863656135474, 3285.3922024425483, 3391.304782519582, 3419.880304189354, 3451.8953934740875, 5594.468525490072, 5629.859253518664, 5661.23573317602, 5719.399463056377, 5775.711214439271, 5673.8581535461635, 5720.0, 5728.541716662668, 5731.627444216123, 5740.1102829061565, 5171.0, 5198.0, 5225.817096401626, 5245.160774276116, 5302.390225124122, 4370.431843860294, 5251.291075704788, 5273.4199238083975, 5279.5512381447625, 5310.7610157543, 5174.586033117342, 5200.557952574036, 5224.425313215544, 5229.267902493654, 5277.340332458442, 5275.129603615399, 5283.709395983219, 5299.258103865462, 5308.6549374290935, 5351.946480535223, 5117.257997590365, 5124.92312615311, 5125.487451254845, 5223.71269580173, 5228.4510126436935, 5653.717314134276, 5658.122990936411, 5700.344460387026, 5724.141378793214, 5727.169560413717, 6267.811965641038, 6282.2461304643275, 6291.8741625167695, 6300.653464059475, 6311.842203944938, 6109.205803245594, 6199.690015499207, 6283.026130949665, 6352.237731472207, 6379.425851673325, 5870.5010074144175, 5890.882182356372, 5903.144044113666, 5911.645301281894, 5919.644821310735, 6314.116023756677, 6317.399847014526, 6322.0516922462, 6322.083297921776, 6334.3032266450655, 6018.578699491081, 6083.4220749029655, 6095.0, 6119.258838874149, 6204.007358822567, 6082.908756368658, 6304.148939893147, 6304.590201636879, 6306.684665766785, 6314.842128946813, 5476.698781566973, 6159.0, 6218.00511918091, 6220.688965551795, 6228.128062071313, 6052.152698622196, 6106.1146133811235, 6118.9846826261555, 6136.079588061826, 6213.2233470816145, 4925.7783399747095, 4943.28322393251, 4943.975280123576, 4949.579285760678, 4972.328735620645, 3549.432090865449, 4176.394422808737, 4477.2164871147415, 4509.346144808985, 4528.592426681548, 5745.253117094751, 5747.827565173052, 5754.251947246915, 5766.0, 5789.478946894716, 6241.063840423882, 6280.717367718464, 6290.528210384148, 6322.083297921776, 6337.366263373626, 4874.878128046827, 5598.692071936001, 5670.8015219467125, 5692.117721753135, 5775.306963164448, 5434.0, 5478.150886612581, 5481.479259470344, 5485.122380419114, 5507.889842203133, 6272.090546870678, 6300.30696623371, 6325.304216536178, 6329.588576742497, 6333.461655759312, 6143.665407145256, 6218.06728990655, 6229.719662615103, 6269.373062693694, 6336.809895703136, 6266.717997690023, 6287.245530536365, 6303.779367722108, 6306.716197771019, 6313.621182729051, 5143.768530416215, 6169.752702870635, 6211.751529938751, 6213.03697926822, 6236.0, 6032.517398608013, 6148.569600127948, 6178.814635560941, 6248.0940263660605, 6267.9373206267355, 6079.679139076085, 6102.115193297036, 6107.144999699956, 6117.644716737863, 6117.888303246575, 6054.243219597551, 6098.878022439438, 6102.176206212104, 6107.511399087974, 6141.7543298267565, 5811.82564523065, 5891.499222565958, 5967.164596956516, 5991.221141251609, 6034.125051867542, 6101.796556807712, 6103.943827031843, 6104.02736356799, 6121.971262793429, 6128.319797384489, 5785.27684039495, 5938.6436813791315, 5963.671998039976, 5965.612235204698, 5973.701314934149], "value": 5689.215776492567, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.87661146407663, 489.8261117303357, 507.3885967409271, 511.88409267796203, 531.1501597444087, 30617.693823061763, 30841.69158308416, 32332.67667323326, 32359.51460728088, 41763.58236417635, 41983.950401239985, 44783.0, 44790.55209447905, 44810.32784508235, 44912.0, 9669.61321547139, 9878.901210987906, 9889.456079915602, 9891.35706179096, 9904.702858914245, 8820.382573219877, 8989.95505022474, 9762.609495620167, 9863.112319891208, 12042.63872083839, 12030.0, 12275.69310767229, 12278.447469863833, 12299.815502767466, 12319.384030798425, 12137.939310303436, 12165.087618428654, 12194.878051219463, 12199.39003049844, 12208.267503949746, 8728.650853965833, 11737.354445505494, 11980.580679676257, 11995.700107497296, 12040.939795301056, 42353.57646423527, 42460.0, 42525.57474425247, 42578.0, 42583.57416425827, 3475.8088305143206, 3608.4226523756074, 3721.144136848513, 4039.596040395966, 4067.186562687474, 31853.044408667778, 33045.50431743526, 33307.66769329224, 33464.33071338584, 33645.6635433645, 3258.6904244096772, 3354.295597924439, 3374.375740487993, 3424.33225521021, 3438.020164253176, 493288.0, 494923.0, 495117.0, 496090.0, 496347.0, 60660.393396066356, 60704.392956069874, 61155.6942215295, 61239.69380153071, 61244.69377653083, 58512.829743404895, 63562.0, 63604.36395636077, 63790.6810465954, 63792.0, 15175.317110730044, 16423.096729679743, 16467.917660411862, 16471.25879335433, 16519.83480165207, 12815.743685126432, 12871.613851584467, 12872.806907896389, 12872.871271287167, 12895.935520322339, 14230.359633816503, 14604.561863144123, 15854.44509442164, 16269.10519921392, 16287.0, 6758.662066896635, 7189.784306470814, 7206.495545311778, 7261.89107163393, 7388.482806203514, 922.7093465558462, 960.5869476125193, 979.314479864098, 984.8621193032981, 1030.8299130643509, 29379.55930661042, 29507.0, 31017.379652406824, 31796.0, 31890.88381906622, 1085.5006696919347, 1091.1270983213556, 1148.9885101148882, 1165.2367699157066, 1245.5578269714122, 15875.968062075928, 15924.92037539805, 16047.358105675876, 16237.51287461378, 16280.591022804974, 188723.05638471994, 192905.03547482172, 193479.0, 193646.0, 193677.0, 52730.0, 61044.69477652583, 61374.386256136866, 61567.46081348002, 61968.380316196264, 1414.9646258843406, 1426.137187001849, 1443.444273954535, 1445.2268036600578, 1447.7104579084014, 31538.0, 32318.515222272155, 32376.0, 32627.18432039218, 33222.66777332292, 52011.479885200664, 52517.474825252786, 52539.47460525499, 52594.0, 52609.0, 591835.0, 608711.0, 611178.0, 611218.0, 613234.0, 33663.83168084242, 40398.79800600978, 41209.0, 41322.93385330661, 41411.585884141976, 49440.6480630166, 49954.0, 50024.0, 50454.747726261136, 50849.74575127249, 32646.34707305888, 39470.02649867553, 40722.18555628812, 41103.0, 42225.31098756138, 33142.33715325632, 35344.0, 36105.638943610225, 40429.0, 40713.18573628452, 39067.21865562616, 39636.2072758549, 39828.20343593054, 39847.40228896511, 39929.0, 34699.74164338816, 35085.0, 35441.268953951876, 36005.45991810073, 37512.0, 23215.53568928646, 24644.630330545413, 26111.0, 26216.0, 26273.868630656725], "value": 20178.323931549716, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.7556447140294, 367.5993167447485, 392.54660866698964, 396.1519511478949, 418.43093392985537, 15881.364745410185, 15993.28030238639, 21240.25659101928, 21240.83175425353, 21359.35921922341, 20927.68608470874, 21500.247491337766, 21579.568408631818, 21908.8904555477, 21909.890450547766, 5352.973235133819, 5936.584439089265, 6208.50331973441, 6216.036514340262, 6245.313015568284, 6153.292371377282, 6182.752689892399, 6188.659623720693, 6219.160413344209, 6225.252969643627, 5911.142884281777, 6178.907316390258, 6191.0, 6201.1008403781425, 6202.875942481171, 5910.586258961875, 6112.205413296265, 6193.0, 6197.163382943312, 6208.906866397007, 5385.515303622654, 5399.83800485986, 5414.83755487336, 5416.0792665247, 5417.729113544307, 21473.24843630481, 25670.743292567022, 25723.485530289476, 25848.0, 26401.3399665008, 2102.98948505258, 2274.4200228941513, 2327.2320134355623, 2333.8949747261413, 2548.5157820014138, 10231.130353919925, 10434.634787782392, 10531.315464494783, 11367.829482557769, 16322.347106115742, 1707.2488105233658, 1795.5331613780381, 1890.0738638067385, 1896.5732710140246, 1915.4445210888964, 83167.0, 103943.48028259886, 104480.0, 117464.82535174624, 125272.0, 11493.42532873353, 11809.645710628523, 11823.940880295544, 11857.644270671894, 11982.520699171935, 14732.55802325932, 14856.702865942621, 14875.776863347059, 14880.255987200597, 14892.70214595702, 8566.61450234741, 8610.407074691202, 8623.655053797904, 8672.523011234316, 8676.696315628922, 8523.0, 8547.615357308936, 8551.230389264996, 8640.092790257058, 8684.78288042804, 3889.5138107736534, 4616.422947131608, 4621.722696638212, 4681.133990211822, 4690.6012988895645, 1698.1725427163635, 1815.2285278756476, 1907.36103405358, 1939.7381353517094, 1944.3389247655864, 389.6415297925915, 401.9718619696593, 714.4888560694095, 999.7000899730052, 1052.957881684724, 10318.0, 11480.368579728029, 12136.029117670565, 12276.693082673004, 12477.93761031189, 433.68124428544996, 445.5989609351545, 449.03009499480964, 451.4650139584603, 461.4347424405077, 6117.816465506042, 6191.752329906753, 6223.564350495421, 6268.1851359323, 6289.088082228142, 618450.8293985972, 619730.0, 627371.0, 634039.4470414737, 639019.0, 82987.5850620743, 108762.48924519894, 108955.0, 142490.0, 148827.0, 946.6592026870321, 980.9901900980899, 990.0396615283164, 990.4107056301489, 992.0773680477225, 19710.343390441125, 20148.093335799633, 20149.49626259355, 20160.0, 20160.17233985292, 47335.053298934516, 47986.760066199444, 51165.465036048976, 52044.479555203965, 55027.724861377035, 76220.61889690516, 78251.60874195592, 80007.59996199982, 81785.77321340065, 82300.0, 11166.665000050012, 12021.939890300493, 12074.818877716665, 12128.0, 12524.0, 13528.188308701509, 13967.022308438312, 13984.580462586138, 14091.295435227992, 14124.576262712135, 10608.946955265174, 11174.776504470028, 11662.591809286634, 11993.220440671328, 12109.636710898687, 11457.312561246019, 11757.176997610086, 11849.407529623657, 11923.284602923852, 12137.393130343271, 11434.08527317796, 11744.17790754639, 11926.403679815801, 12002.879971200176, 12104.576339828063, 10439.94780026095, 12243.510259589517, 12355.93822030884, 12465.376731163224, 12487.188332758342, 11753.647390578297, 11783.998360139483, 11852.762944740884, 11957.880421196025, 12089.274643521418], "value": 9360.890399728778, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1493.6639256167364, 1537.3543111893005, 1755.271562301645, 1914.5022294203495, 1918.175184670591, 7469.925300746991, 8381.748547543568, 8620.956895215531, 8806.295496360292, 8850.336224783143, 7207.459440541954, 7933.76198714038, 8327.791805204874, 8571.871421928661, 8624.008239052511, 2347.835651504395, 2377.72656144543, 2378.262738551046, 2378.357843382286, 2383.249276477904, 2360.0, 2380.3811009137576, 2405.458771776354, 2408.7233766103905, 2415.9758402416014, 2101.359085478928, 2166.5558560495137, 2253.2226381898217, 2322.094383190557, 2325.5930212212793, 2350.330155905567, 2354.9646755298513, 2379.238643634038, 2379.8929048192786, 2387.0571124405938, 2348.9177878774162, 2360.0677732295817, 2362.9172978945826, 2364.598018336887, 2365.420471984358, 8605.311575074042, 8750.824983500357, 8752.387332886701, 8753.949526056851, 8766.167214114712, 5171.715555644438, 5329.840104796862, 5331.600129990227, 5345.438728933484, 5347.598930080259, 3595.496630471736, 3602.441621548664, 3661.1579336752698, 3672.944905826415, 3675.44868269759, 1097.9231453798238, 1100.9889901100967, 1102.3551222534827, 1119.8880111988817, 1120.1263014848432, 11509.366984815832, 11520.539178432853, 11527.48126334317, 11549.595764148215, 11550.88449115515, 3923.8228531440454, 4495.57292057254, 4647.117917243521, 4729.716217026989, 4763.237881938873, 9701.417914925127, 9710.6115755369, 9711.708648740549, 9714.805703886024, 9754.975727548504, 5287.206918962186, 5288.0745869472685, 5303.178007408857, 5304.230886521433, 5315.707636079976, 4723.527647235249, 4861.343718598015, 4869.1722407190155, 4871.269309603517, 4873.585745211697, 3841.5582208045876, 3870.41943708446, 3906.628870257378, 4001.6398524132387, 4017.457643218128, 1176.2178151529138, 1218.993905030487, 1267.182667179664, 1285.2609749393948, 1299.1620404838825, 721.6860665610476, 767.1561282589107, 913.4701872913694, 925.3615005646217, 947.5262368815593, 5165.457626949116, 5737.250138707735, 5951.851203719942, 5959.404059594006, 6092.45586516268, 938.2869019545195, 954.6229239450378, 955.3360414511986, 959.6017652674202, 987.3039507147444, 3944.60553944609, 3988.3818008208773, 4063.654589359878, 4076.6331030207425, 4085.7957102144774, 9851.310408271353, 10699.946500267604, 10712.625058122929, 10717.83923241152, 10742.3017503862, 8719.389642725075, 9131.0, 9279.932807727062, 10598.947005264925, 10689.625863094754, 7335.926640733631, 8199.877001844978, 8228.382871284677, 8237.258646721824, 8252.504849709037, 5795.449432303924, 5927.911081333869, 5957.106434034929, 5992.940070599238, 6015.097735339734, 9499.840523084295, 9832.967538408364, 9951.253655975686, 9952.55213515379, 9960.302778805413, 7966.482178658369, 9841.950790246003, 10168.084872361524, 10220.540075696463, 10221.386716797017, 7255.2019277880445, 7265.237150099163, 7346.5592064476305, 7349.4855360124275, 7357.1907090218965, 8090.6359213836695, 8103.108658047492, 8143.714969976022, 8175.0, 8177.877331839908, 6008.279006519071, 7043.2604576518725, 7324.96337518309, 7432.665530051053, 7545.094588649397, 7239.927600723925, 7262.9273707262255, 7464.701411943462, 7473.813154671177, 7501.062367204099, 6803.625800581016, 6808.761693340709, 6883.0, 6884.965575172092, 6886.2769409213315, 4843.0, 4966.155753521838, 4979.278004689372, 5054.69671819692, 5057.418396884405, 5013.24801279811, 5041.823536176363, 5066.746662666926, 5099.643024988363, 5155.587552995827], "value": 4547.627963564441, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.59529421164063, 317.477749102726, 320.0877499127486, 324.30922135850636, 325.60926887734723, 5498.477644623759, 5609.429359779263, 5609.831705048845, 5665.490105890469, 5685.8578535536635, 5454.945450545493, 5491.972540137293, 5543.695096769676, 5577.852526777189, 5592.552595792337, 3442.5180474733556, 3872.419137129432, 4029.576894426087, 4076.7961601919787, 4112.568180341066, 3455.5507783988046, 3694.8522059117604, 4165.916681666365, 4178.56125106864, 4209.936850947238, 4137.0, 4144.647704945083, 4179.519355274137, 4201.76890271035, 4211.136716973013, 3978.0850404407147, 4055.2497787909333, 4218.367244913287, 4230.371307046794, 4231.534531201565, 2964.880110720845, 3372.730181585467, 3463.3592785334795, 3550.8934731958084, 3598.5142005829402, 5282.336064139784, 5291.417944026154, 5298.311219541473, 5301.893962120775, 5334.0665383557725, 3661.6521430464063, 3969.8412063517426, 4543.31850222466, 4591.6097131743845, 4759.025608994379, 5180.430152683201, 5742.109972954198, 5752.325168796529, 5766.88466230673, 5789.247397838254, 3467.583889933199, 3629.6551827576336, 3769.227308401771, 3974.2647610192207, 3981.3278423062384, 4245.830166793325, 5619.7471113799975, 6045.939540604581, 6125.540584456184, 6135.938640613582, 3425.2293234022645, 3711.6659500645073, 4090.406891000788, 4102.3026085565525, 4120.340745480708, 3423.3666771647327, 3724.7951362675312, 4003.0, 4071.5114186297833, 4217.97891010543, 4377.868663940087, 4590.495045544987, 4823.903521929542, 4848.684835485682, 4859.586935110555, 5552.501923787988, 5554.972225138929, 5853.64878107315, 5866.67733274674, 5890.734916928748, 1202.4889421995792, 1278.6547632139457, 1328.35574746248, 1340.9329533523285, 1423.565812427199, 791.8733002719537, 839.9244068033906, 876.8334016536837, 885.9202671759573, 888.1828717579842, 3936.6653834423823, 4280.743155410685, 4291.09510345584, 4408.170609197211, 4427.29163333865, 4269.0, 4978.5768209701855, 5010.023045506165, 5023.296738456619, 5056.797728090909, 4052.473178486837, 4374.978125109417, 4425.026494171278, 4513.435820522434, 4546.477155127202, 2422.3770074050076, 2632.499825033276, 2665.7867370610693, 2674.8528830914097, 2678.076063758, 4350.695451318377, 4744.0, 4857.967886654069, 4971.080350135237, 5117.4903403496055, 4708.399144290957, 4782.689125206851, 4796.043264306961, 5137.845864624067, 5764.424725119113, 5243.554297884646, 5928.199693041471, 6248.968755156195, 6255.56211065221, 6257.624542527463, 5854.707264636836, 6001.129836173817, 6039.667818270037, 6040.728167232573, 6067.423594758491, 4731.952680473151, 5112.923306150336, 5123.5644970177955, 5845.006348920779, 5912.881742365214, 4904.8773780655765, 5094.770735316845, 5901.29184497863, 6169.413905678954, 6172.796304720537, 3371.5165327255927, 3981.4425980362194, 4056.20903923732, 4056.9391459127546, 4107.938380924348, 5363.731813409392, 5814.505767009851, 5830.562707797017, 5916.911246331222, 6029.909551356645, 5705.971470142788, 5784.566157538117, 5785.56608254391, 5819.418058194146, 5858.970705146447, 5480.698561579152, 5800.214822771315, 5820.243368362086, 5825.388334225015, 5873.295204575478, 5330.8933821324135, 5706.0, 5724.771009159587, 5743.052396354722, 5757.8560535986935, 5337.0, 5431.402545719889, 5764.538836893123, 5844.6785426801935, 5883.0, 5394.676319420847, 5576.86057848557, 5682.499700132094, 5686.578355411148, 5737.512311453574], "value": 4079.2197187669426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.7374139732011, 755.5466719968016, 807.2895851650546, 817.5503473089799, 851.2423942691006, 15491.767623485643, 16232.594185145363, 16236.594085147877, 16245.26896289667, 17145.05702186379, 15900.125493097876, 16775.161241937916, 16791.83208167918, 16803.0, 16809.15954202291, 715.8389362393472, 739.5858319341186, 885.614757580453, 920.6409500294891, 922.7416323429417, 890.132121181849, 919.6873063158524, 919.7516670498961, 924.9676261330854, 931.4364809290349, 975.0444564326964, 976.0434773921561, 976.7460460280342, 979.3487330924927, 983.0464449483989, 740.152525358467, 765.8659734546487, 768.8616049111158, 961.5432669481975, 962.5957098018814, 572.879695263995, 669.9162604674416, 695.2560759986816, 721.2967356827052, 722.8843385058366, 13236.676332366686, 14971.176585287805, 15369.846301536953, 15408.614784630361, 15417.0, 14222.359993800304, 14363.066400683922, 14428.278586070655, 14486.637834054232, 14557.344919478648, 12977.675558111123, 13199.40602672882, 13239.338033098404, 13241.867581324159, 13638.386272617756, 3897.649211570972, 3925.60743925608, 4020.979895100506, 4022.557518672944, 4040.030392705759, 120818.99999999913, 123716.0, 124076.0, 124977.0, 125081.99999999908, 11421.0, 11592.47833847462, 11630.76738465226, 11888.821667674993, 12028.977536909286, 23326.416839579146, 23344.7665523346, 23368.88315558445, 23403.88298058499, 23411.882940585187, 7904.683812647545, 8017.559034253172, 8063.758087257392, 8086.959565202136, 8236.258736713724, 4325.307950727932, 4412.580804823537, 4425.380446737459, 4602.7008244464005, 4720.33915251865, 3416.538767266437, 3541.220931395089, 3547.184147646056, 3551.1477245460906, 3555.8577656893954, 606.5663050918527, 663.4161937494973, 672.3444641474621, 673.2829536543566, 680.8706345794283, 633.2622494793513, 645.6112384178465, 647.5726020826232, 652.5236577298599, 659.1859054068221, 7838.844317812647, 8699.130086991378, 8903.910960890309, 9120.635174593075, 9179.77050573741, 560.9691466969274, 611.3886113886114, 617.9505639548826, 630.9842253943688, 631.520044765972, 4381.84663536781, 4411.139827733561, 4427.313766366218, 4501.932471012938, 4505.549445055533, 145936.40635936533, 148406.0, 151368.0, 152486.0, 157729.0, 125022.74977250292, 126176.73823261833, 127047.0, 129551.0, 130845.0, 93958.0, 99280.50359748155, 100123.99752005065, 101720.49139754254, 109886.0, 9835.262355323237, 9861.265094537477, 10064.446455444728, 10106.747331316776, 10218.846717299099, 50134.0, 63008.36991630209, 64074.679626601566, 65631.44534348654, 65637.0, 92834.0, 95899.0, 97240.0, 98491.01508984818, 99993.50003250228, 13361.53234636783, 13378.331083445983, 13399.12905661129, 13958.790618140532, 14039.0, 14979.475718349806, 14991.850081499482, 15137.167455790037, 15544.300506477462, 15739.976901501825, 14113.576592702235, 14163.291835407983, 14183.432662693376, 14314.355853986388, 14623.009077959136, 13785.379657915217, 14185.007049506848, 14341.713165736835, 14528.636784080483, 14661.560153195422, 13650.453981840616, 13888.166709997433, 13916.652083697989, 13930.303484825921, 13936.79094813599, 11327.943360283145, 11434.94282528582, 11502.48238829238, 11713.472893719632, 11829.585964491202, 8928.553572321229, 11235.719107022063, 11365.723162748458, 11698.94150529242, 11708.0], "value": 8041.3746824747095, "unit": "score"}], "name": "dromaeo_css", "value": 7370.533806622903, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 114.0, 113.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 108.0, 157.0, 107.0, 107.0, 130.0, 107.0, 139.0, 104.0], "value": 120.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 181.0, 185.0, 185.0, 183.0, 180.0, 183.0, 183.0, 185.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 57.0, 104.0, 58.0, 57.0, 65.0, 62.0, 80.0, 58.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 91.0, 76.0, 78.0, 69.0, 81.0, 71.0, 89.0, 134.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 88.0, 85.0, 89.0, 87.0, 134.0, 118.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 87.0, 83.0, 84.0, 83.0, 84.0, 87.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 53.0, 53.0, 53.0, 54.0, 54.0, 52.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 78.0, 69.0, 73.0, 73.0, 70.0, 72.0, 71.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 85.0, 83.0, 84.0, 86.0, 100.0, 100.0, 81.0, 82.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 135.0, 136.0, 136.0, 135.0, 131.0, 132.0, 176.0, 136.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 180.0, 229.0, 171.0, 165.0, 171.0, 171.0, 172.0, 169.0, 170.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 69.0, 69.0, 68.0, 67.0, 67.0, 67.0, 64.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1437.7, "alertThreshold": 2.0}]} 03:41:55 INFO - Return code: 0 03:41:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:41:55 INFO - # TBPL SUCCESS # 03:41:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:55 INFO - 2016-07-17 03:41:55.770 screenresolution[1690:9688] starting screenresolution argv=screenresolution get 03:41:55 INFO - 2016-07-17 03:41:55.773 screenresolution[1690:9688] Display 0: 1600x1200x32@60 03:41:55 INFO - 2016-07-17 03:41:55.778 screenresolution[1691:9691] starting screenresolution argv=screenresolution list 03:41:55 INFO - Available Modes on Display 0 03:41:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:56 INFO - Intel Iris: 03:41:56 INFO - Chipset Model: Intel Iris 03:41:56 INFO - Type: GPU 03:41:56 INFO - Bus: Built-In 03:41:56 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:56 INFO - Vendor: Intel (0x8086) 03:41:56 INFO - Device ID: 0x0a2e 03:41:56 INFO - Revision ID: 0x0009 03:41:56 INFO - Displays: 03:41:56 INFO - Display: 03:41:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:56 INFO - Display Serial Number: 1600x1200 60 03:41:56 INFO - Main Display: Yes 03:41:56 INFO - Mirror: Off 03:41:56 INFO - Online: Yes 03:41:56 INFO - Rotation: Supported 03:41:56 INFO - Return code: 0 03:41:56 INFO - Running post-action listener: _resource_record_post_action 03:41:56 INFO - ##### 03:41:56 INFO - ##### Finished run-tests step (success) 03:41:56 INFO - ##### 03:41:56 INFO - Running post-run listener: _resource_record_post_run 03:41:56 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37090816; Write bytes: 705017344; Read time: 241; Write time: 1995 03:41:56 INFO - TinderboxPrint: CPU usage
53.6% 03:41:56 INFO - TinderboxPrint: I/O read bytes / time
37,090,816 / 241 03:41:56 INFO - TinderboxPrint: I/O write bytes / time
705,017,344 / 1,995 03:41:56 INFO - TinderboxPrint: CPU idle
2,246.2 (46.4%) 03:41:56 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 03:41:56 INFO - TinderboxPrint: CPU user
1,626.0 (33.6%) 03:41:56 INFO - TinderboxPrint: Swap in / out
444,141,568 / 0 03:41:56 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390537728; Write bytes: 384812544; Read time: 29089; Write time: 825 03:41:56 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 36132352; Write bytes: 294973440; Read time: 228; Write time: 1107 03:41:56 INFO - Running post-run listener: _upload_blobber_files 03:41:56 INFO - Blob upload gear active. 03:41:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:41:56 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:41:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:58 INFO - (blobuploader) - INFO - Done attempting. 03:41:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:41:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:41:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:59 INFO - (blobuploader) - INFO - Done attempting. 03:41:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:41:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:01 INFO - (blobuploader) - INFO - Done attempting. 03:42:01 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:01 INFO - Return code: 0 03:42:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19137200d78fb6c72f1c5e2b4f5a59fe2c068eab27aab4e7250dabe9d205d833cd6245b859db6c4ce33d0c1c870d33f7dc802e12e1b20ebe97b6b4ff66391656", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fcb83043487884db7af1cabafa7425d9b6f1cc7abf3a4558e2af1f466d62a3af78d08737c2e2890b5ca12cbc64e09190791185a8abb2a2a9c530fd89bd8950d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcf961348566213af9b009b0f27d86c5d703aeb7a9e60d2caa502a293e26f295cc296f5cf0289762a0ae30b52210c6d26c15af49f9e3c45312820ea21be4d54"} 03:42:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:01 INFO - Contents: 03:42:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19137200d78fb6c72f1c5e2b4f5a59fe2c068eab27aab4e7250dabe9d205d833cd6245b859db6c4ce33d0c1c870d33f7dc802e12e1b20ebe97b6b4ff66391656", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fcb83043487884db7af1cabafa7425d9b6f1cc7abf3a4558e2af1f466d62a3af78d08737c2e2890b5ca12cbc64e09190791185a8abb2a2a9c530fd89bd8950d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcf961348566213af9b009b0f27d86c5d703aeb7a9e60d2caa502a293e26f295cc296f5cf0289762a0ae30b52210c6d26c15af49f9e3c45312820ea21be4d54"} 03:42:01 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:01 INFO - Copying logs to upload dir... 03:42:01 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.571768 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-17 03:42:01.887879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:01.891263) ========= 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.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19137200d78fb6c72f1c5e2b4f5a59fe2c068eab27aab4e7250dabe9d205d833cd6245b859db6c4ce33d0c1c870d33f7dc802e12e1b20ebe97b6b4ff66391656", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fcb83043487884db7af1cabafa7425d9b6f1cc7abf3a4558e2af1f466d62a3af78d08737c2e2890b5ca12cbc64e09190791185a8abb2a2a9c530fd89bd8950d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcf961348566213af9b009b0f27d86c5d703aeb7a9e60d2caa502a293e26f295cc296f5cf0289762a0ae30b52210c6d26c15af49f9e3c45312820ea21be4d54"} build_url:https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011017 build_url: 'https://queue.taskcluster.net/v1/task/E_ga-2xZTAOUAbuKd7ijHg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/19137200d78fb6c72f1c5e2b4f5a59fe2c068eab27aab4e7250dabe9d205d833cd6245b859db6c4ce33d0c1c870d33f7dc802e12e1b20ebe97b6b4ff66391656", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fcb83043487884db7af1cabafa7425d9b6f1cc7abf3a4558e2af1f466d62a3af78d08737c2e2890b5ca12cbc64e09190791185a8abb2a2a9c530fd89bd8950d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/adcf961348566213af9b009b0f27d86c5d703aeb7a9e60d2caa502a293e26f295cc296f5cf0289762a0ae30b52210c6d26c15af49f9e3c45312820ea21be4d54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:01.924310) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:01.924665) ========= 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.RgIk5POKKT/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.pzvVqPBExr/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/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.005068 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 03:42:01.974752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:42:01.975276) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 03:42:01.975842) ========= ========= Total master_lag: 0.18 =========