builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1468135923.69 results: success (0) buildid: 20160709233726 builduid: 9d54b5c0336140659e42e7161589580b revision: c22afe017b530907be3bea3d1e1e91ba8e33fac7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:03.687521) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:03.687969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:03.688282) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:03.942377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:03.942891) ========= 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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-10 00:32:03-- 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% 11.9M=0.001s 2016-07-10 00:32:04 (11.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.485684 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:04.484752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:04.485084) ========= 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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.027083 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:04.542775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 00:32:04.543095) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev c22afe017b530907be3bea3d1e1e91ba8e33fac7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev c22afe017b530907be3bea3d1e1e91ba8e33fac7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-10 00:32:04,653 truncating revision to first 12 chars 2016-07-10 00:32:04,653 Setting DEBUG logging. 2016-07-10 00:32:04,653 attempt 1/10 2016-07-10 00:32:04,653 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/c22afe017b53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 00:32:05,938 unpacking tar archive at: autoland-c22afe017b53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.544943 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 00:32:06.133852) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:06.134142) ========= script_repo_revision: c22afe017b530907be3bea3d1e1e91ba8e33fac7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:06.134498) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:06.134760) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 00:32:06.171067) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-10 00:32:06.171330) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:06 INFO - MultiFileLogger online at 20160710 00:32:06 in /builds/slave/test 00:32:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 00:32:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:32:06 INFO - {'append_to_log': False, 00:32:06 INFO - 'base_work_dir': '/builds/slave/test', 00:32:06 INFO - 'blob_upload_branch': 'Autoland', 00:32:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:32:06 INFO - 'branch': 'Autoland', 00:32:06 INFO - 'buildbot_json_path': 'buildprops.json', 00:32:06 INFO - 'config_files': ('talos/mac_config.py',), 00:32:06 INFO - 'default_actions': ('clobber', 00:32:06 INFO - 'read-buildbot-config', 00:32:06 INFO - 'download-and-extract', 00:32:06 INFO - 'populate-webroot', 00:32:06 INFO - 'create-virtualenv', 00:32:06 INFO - 'install', 00:32:06 INFO - 'run-tests'), 00:32:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:32:06 INFO - 'download_minidump_stackwalk': True, 00:32:06 INFO - 'download_symbols': 'ondemand', 00:32:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:32:06 INFO - 'tooltool.py': '/tools/tooltool.py', 00:32:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:32:06 INFO - '/tools/misc-python/virtualenv.py')}, 00:32:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:32:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:32:06 INFO - 'installer_path': 'installer.exe', 00:32:06 INFO - 'log_level': 'info', 00:32:06 INFO - 'log_name': 'talos', 00:32:06 INFO - 'log_to_console': True, 00:32:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:32:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:32:06 INFO - 'opt_config_files': (), 00:32:06 INFO - 'pip_index': False, 00:32:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:32:06 INFO - 'cmd': ('bash', 00:32:06 INFO - '-c', 00:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:32:06 INFO - 'enabled': True, 00:32:06 INFO - 'halt_on_failure': False, 00:32:06 INFO - 'name': 'check_screen_resolution'},), 00:32:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:32:06 INFO - 'cmd': ('bash', 00:32:06 INFO - '-c', 00:32:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:32:06 INFO - 'enabled': True, 00:32:06 INFO - 'halt_on_failure': False, 00:32:06 INFO - 'name': 'check_screen_resolution'},), 00:32:06 INFO - 'run_cmd_checks_enabled': True, 00:32:06 INFO - 'sps_profile': False, 00:32:06 INFO - 'sps_profile_interval': 0, 00:32:06 INFO - 'suite': 'dromaeojs', 00:32:06 INFO - 'system_bits': '32', 00:32:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:32:06 INFO - 'title': 't-yosemite-r7-0286', 00:32:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:32:06 INFO - 'use_talos_json': True, 00:32:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:32:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:32:06 INFO - 'work_dir': 'build'} 00:32:06 INFO - ##### 00:32:06 INFO - ##### Running clobber step. 00:32:06 INFO - ##### 00:32:06 INFO - Running pre-action listener: _resource_record_pre_action 00:32:06 INFO - Running main action method: clobber 00:32:06 INFO - rmtree: /builds/slave/test/build 00:32:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:32:08 INFO - Running post-action listener: _resource_record_post_action 00:32:08 INFO - ##### 00:32:08 INFO - ##### Finished clobber step (success) 00:32:08 INFO - ##### 00:32:08 INFO - ##### 00:32:08 INFO - ##### Running read-buildbot-config step. 00:32:08 INFO - ##### 00:32:08 INFO - Running pre-action listener: _resource_record_pre_action 00:32:08 INFO - Running main action method: read_buildbot_config 00:32:08 INFO - Using buildbot properties: 00:32:08 INFO - { 00:32:08 INFO - "project": "", 00:32:08 INFO - "product": "firefox", 00:32:08 INFO - "script_repo_revision": "production", 00:32:08 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 00:32:08 INFO - "repository": "", 00:32:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 00:32:08 INFO - "buildid": "20160709233726", 00:32:08 INFO - "pgo_build": "False", 00:32:08 INFO - "basedir": "/builds/slave/test", 00:32:08 INFO - "buildnumber": 52, 00:32:08 INFO - "slavename": "t-yosemite-r7-0286", 00:32:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 00:32:08 INFO - "platform": "yosemite_r7", 00:32:08 INFO - "scheduler": "tests-autoland-macosx64-talos", 00:32:08 INFO - "branch": "autoland", 00:32:08 INFO - "repo_path": "integration/autoland", 00:32:08 INFO - "revision": "c22afe017b530907be3bea3d1e1e91ba8e33fac7", 00:32:08 INFO - "stage_platform": "macosx64", 00:32:08 INFO - "builduid": "9d54b5c0336140659e42e7161589580b", 00:32:08 INFO - "slavebuilddir": "test" 00:32:08 INFO - } 00:32:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 00:32:08 INFO - Running post-action listener: _resource_record_post_action 00:32:08 INFO - ##### 00:32:08 INFO - ##### Finished read-buildbot-config step (success) 00:32:08 INFO - ##### 00:32:08 INFO - ##### 00:32:08 INFO - ##### Running download-and-extract step. 00:32:08 INFO - ##### 00:32:08 INFO - Running pre-action listener: _resource_record_pre_action 00:32:08 INFO - Running main action method: download_and_extract 00:32:08 INFO - mkdir: /builds/slave/test/build/tests 00:32:08 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')]} 00:32:08 INFO - https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:32:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 00:32:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 00:32:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:10 INFO - Downloaded 1199 bytes. 00:32:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 00:32:10 INFO - Using the following test package requirements: 00:32:10 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 00:32:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 00:32:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 00:32:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'jsshell-mac.zip'], 00:32:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 00:32:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 00:32:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 00:32:10 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 00:32:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 00:32:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 00:32:10 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 00:32:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:32: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')]} 00:32:10 INFO - https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:32:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 00:32:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 00:32:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:11 INFO - Downloaded 18806831 bytes. 00:32:11 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'] 00:32:11 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 00:32:15 INFO - Return code: 0 00:32:15 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 00:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:15 INFO - https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:32:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 00:32:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 00:32:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:19 INFO - Downloaded 18806831 bytes. 00:32:19 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'] 00:32:19 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 00:32:23 INFO - Return code: 0 00:32: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')]} 00:32:23 INFO - https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:32:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 00:32:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 00:32:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:24 INFO - Downloaded 14403333 bytes. 00:32:24 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'] 00:32:24 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 00:32:24 INFO - Return code: 0 00:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:24 INFO - https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 00:32:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 00:32:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 00:32:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 00:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VcaCszy3QIujx4_ZscrS5Q/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 00:32:27 INFO - Downloaded 112385807 bytes. 00:32:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 00:32:27 INFO - mkdir: /builds/slave/test/properties 00:32:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:32:27 INFO - Writing to file /builds/slave/test/properties/build_url 00:32:27 INFO - Contents: 00:32:27 INFO - build_url:https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 00:32:28 INFO - Running post-action listener: _resource_record_post_action 00:32:28 INFO - Running post-action listener: set_extra_try_arguments 00:32:28 INFO - ##### 00:32:28 INFO - ##### Finished download-and-extract step (success) 00:32:28 INFO - ##### 00:32:28 INFO - ##### 00:32:28 INFO - ##### Running populate-webroot step. 00:32:28 INFO - ##### 00:32:28 INFO - Running pre-action listener: _resource_record_pre_action 00:32:28 INFO - Running main action method: populate_webroot 00:32:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:32:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:32:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:32:28 INFO - '--tppagecycles', 00:32:28 INFO - '7'], 00:32:28 INFO - 'tests': ['tsvgm']}}, 00:32:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:32:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:32:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:32:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:32:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:32:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:32:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['damp', 'tps']}, 00:32:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'tests': ['damp', 'tps']}, 00:32:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['dromaeo_dom']}, 00:32:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:32:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['basic_compositor_video']}, 00:32:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:32:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'other-e10s': {'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['a11yr', 00:32:28 INFO - 'ts_paint', 00:32:28 INFO - 'tpaint', 00:32:28 INFO - 'sessionrestore', 00:32:28 INFO - 'sessionrestore_no_auto_restore', 00:32:28 INFO - 'tabpaint']}, 00:32:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['tsvgx', 00:32:28 INFO - 'tsvgr_opacity', 00:32:28 INFO - 'tart', 00:32:28 INFO - 'tscrollx', 00:32:28 INFO - 'cart']}, 00:32:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:32:28 INFO - 'tsvgr_opacity', 00:32:28 INFO - 'tart', 00:32:28 INFO - 'tscrollx', 00:32:28 INFO - 'cart']}, 00:32:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'talos_options': ['--disable-e10s'], 00:32:28 INFO - 'tests': ['tp5o']}, 00:32:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'tests': ['tp5o']}, 00:32:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'talos_options': ['--disable-e10s', 00:32:28 INFO - '--xperf_path', 00:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:32:28 INFO - 'tests': ['tp5n']}, 00:32:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:32:28 INFO - 'talos_options': ['--xperf_path', 00:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:32:28 INFO - 'tests': ['tp5n']}}, 00:32:28 INFO - 'talos.zip': {'path': '', 00:32:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:32:28 INFO - Running post-action listener: _resource_record_post_action 00:32:28 INFO - ##### 00:32:28 INFO - ##### Finished populate-webroot step (success) 00:32:28 INFO - ##### 00:32:28 INFO - ##### 00:32:28 INFO - ##### Running create-virtualenv step. 00:32:28 INFO - ##### 00:32:28 INFO - Running pre-action listener: _resource_record_pre_action 00:32:28 INFO - Running main action method: create_virtualenv 00:32:28 INFO - Creating virtualenv /builds/slave/test/build/venv 00:32:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 00:32:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:32:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:32:28 INFO - Using real prefix '/tools/python27' 00:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 00:32:32 INFO - Installing pip.................done. 00:32:32 INFO - Return code: 0 00:32:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:32 INFO - 'HOME': '/Users/cltbld', 00:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:32 INFO - 'LOGNAME': 'cltbld', 00:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:32 INFO - 'MOZ_NO_REMOTE': '1', 00:32:32 INFO - 'NO_EM_RESTART': '1', 00:32:32 INFO - 'PAGER': '/bin/cat', 00:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:32 INFO - 'PWD': '/builds/slave/test', 00:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:32 INFO - 'SHELL': '/bin/bash', 00:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:32 INFO - 'USER': 'cltbld', 00:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:32 INFO - 'XPC_FLAGS': '0x0', 00:32:32 INFO - 'XPC_SERVICE_NAME': '0', 00:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 00:32:32 INFO - Cleaning up... 00:32:32 INFO - Return code: 0 00:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:32 INFO - 'HOME': '/Users/cltbld', 00:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:32 INFO - 'LOGNAME': 'cltbld', 00:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:32 INFO - 'MOZ_NO_REMOTE': '1', 00:32:32 INFO - 'NO_EM_RESTART': '1', 00:32:32 INFO - 'PAGER': '/bin/cat', 00:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:32 INFO - 'PWD': '/builds/slave/test', 00:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:32 INFO - 'SHELL': '/bin/bash', 00:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:32 INFO - 'USER': 'cltbld', 00:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:32 INFO - 'XPC_FLAGS': '0x0', 00:32:32 INFO - 'XPC_SERVICE_NAME': '0', 00:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:32 INFO - Downloading/unpacking psutil>=3.1.1 00:32: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 00:32: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 00:32: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 00:32: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 00:32: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 00:32: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 00:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:32:36 INFO - Installing collected packages: psutil 00:32:36 INFO - Running setup.py install for psutil 00:32:36 INFO - building 'psutil._psutil_osx' extension 00:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 00:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 00:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 00:32:37 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 00:32:37 INFO - building 'psutil._psutil_posix' extension 00:32:37 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 00:32:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:32:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:32:37 INFO - ^ 00:32:37 INFO - 1 warning generated. 00:32:37 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 00:32:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:32:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:32:37 INFO - Successfully installed psutil 00:32:37 INFO - Cleaning up... 00:32:37 INFO - Return code: 0 00:32:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:37 INFO - 'HOME': '/Users/cltbld', 00:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:37 INFO - 'LOGNAME': 'cltbld', 00:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:37 INFO - 'MOZ_NO_REMOTE': '1', 00:32:37 INFO - 'NO_EM_RESTART': '1', 00:32:37 INFO - 'PAGER': '/bin/cat', 00:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:37 INFO - 'PWD': '/builds/slave/test', 00:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:37 INFO - 'SHELL': '/bin/bash', 00:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:37 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:37 INFO - 'USER': 'cltbld', 00:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:37 INFO - 'XPC_FLAGS': '0x0', 00:32:37 INFO - 'XPC_SERVICE_NAME': '0', 00:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:32:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:32:40 INFO - Installing collected packages: mozsystemmonitor 00:32:40 INFO - Running setup.py install for mozsystemmonitor 00:32:40 INFO - Successfully installed mozsystemmonitor 00:32:40 INFO - Cleaning up... 00:32:40 INFO - Return code: 0 00:32:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:32:40 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')]} 00:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:40 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')]} 00:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:40 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 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:40 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 00:32:40 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 00:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:40 INFO - 'HOME': '/Users/cltbld', 00:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:40 INFO - 'LOGNAME': 'cltbld', 00:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:40 INFO - 'MOZ_NO_REMOTE': '1', 00:32:40 INFO - 'NO_EM_RESTART': '1', 00:32:40 INFO - 'PAGER': '/bin/cat', 00:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:40 INFO - 'PWD': '/builds/slave/test', 00:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:40 INFO - 'SHELL': '/bin/bash', 00:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:40 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:40 INFO - 'USER': 'cltbld', 00:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:40 INFO - 'XPC_FLAGS': '0x0', 00:32:40 INFO - 'XPC_SERVICE_NAME': '0', 00:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:40 INFO - Downloading/unpacking jsonschema==2.5.1 00:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:32:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:43 INFO - Downloading functools32-3.2.3-2.tar.gz 00:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:32:43 INFO - warning: no files found matching '*.txt' 00:32:43 INFO - no previously-included directories found matching 'build' 00:32:43 INFO - no previously-included directories found matching 'dist' 00:32:43 INFO - no previously-included directories found matching '.git*' 00:32:43 INFO - Installing collected packages: jsonschema, functools32 00:32:43 INFO - Running setup.py install for functools32 00:32:43 INFO - warning: no files found matching '*.txt' 00:32:43 INFO - no previously-included directories found matching 'build' 00:32:43 INFO - no previously-included directories found matching 'dist' 00:32:43 INFO - no previously-included directories found matching '.git*' 00:32:43 INFO - Successfully installed jsonschema functools32 00:32:43 INFO - Cleaning up... 00:32:43 INFO - Return code: 0 00:32:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:32:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:43 INFO - 'HOME': '/Users/cltbld', 00:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:43 INFO - 'LOGNAME': 'cltbld', 00:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:43 INFO - 'MOZ_NO_REMOTE': '1', 00:32:43 INFO - 'NO_EM_RESTART': '1', 00:32:43 INFO - 'PAGER': '/bin/cat', 00:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:43 INFO - 'PWD': '/builds/slave/test', 00:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:43 INFO - 'SHELL': '/bin/bash', 00:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:43 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:43 INFO - 'USER': 'cltbld', 00:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:43 INFO - 'XPC_FLAGS': '0x0', 00:32:43 INFO - 'XPC_SERVICE_NAME': '0', 00:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:44 INFO - Downloading/unpacking blobuploader==1.2.4 00:32:44 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 00:32:44 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 00:32:44 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 00:32:44 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 00:32:44 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 00:32:44 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 00:32:46 INFO - Downloading blobuploader-1.2.4.tar.gz 00:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:32:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:32:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:32:47 INFO - Downloading docopt-0.6.1.tar.gz 00:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:32:47 INFO - Installing collected packages: blobuploader, requests, docopt 00:32:47 INFO - Running setup.py install for blobuploader 00:32:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:32:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:32:47 INFO - Running setup.py install for requests 00:32:48 INFO - Running setup.py install for docopt 00:32:48 INFO - Successfully installed blobuploader requests docopt 00:32:48 INFO - Cleaning up... 00:32:48 INFO - Return code: 0 00:32:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 00:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:48 INFO - 'HOME': '/Users/cltbld', 00:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:48 INFO - 'LOGNAME': 'cltbld', 00:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:48 INFO - 'MOZ_NO_REMOTE': '1', 00:32:48 INFO - 'NO_EM_RESTART': '1', 00:32:48 INFO - 'PAGER': '/bin/cat', 00:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:48 INFO - 'PWD': '/builds/slave/test', 00:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:48 INFO - 'SHELL': '/bin/bash', 00:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:48 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:48 INFO - 'USER': 'cltbld', 00:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:48 INFO - 'XPC_FLAGS': '0x0', 00:32:48 INFO - 'XPC_SERVICE_NAME': '0', 00:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:32:48 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-2zOByG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-DoEeJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pHTRXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LgxE0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-HjHjkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Sn_wse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-weEEz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-OGZs5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-8NUEkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-SkKzhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:32:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-mdHkW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-JCQDof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-g8fPmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9LEp7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-EpoNsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-TjIKzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:32:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WF1Z9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:32:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:32:50 INFO - Running setup.py install for manifestparser 00:32:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:32:50 INFO - Running setup.py install for mozcrash 00:32:50 INFO - Running setup.py install for mozdebug 00:32:51 INFO - Running setup.py install for mozdevice 00:32:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Running setup.py install for mozfile 00:32:51 INFO - Running setup.py install for mozhttpd 00:32:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Running setup.py install for mozinfo 00:32:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Running setup.py install for mozInstall 00:32:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:32:51 INFO - Running setup.py install for mozleak 00:32:52 INFO - Running setup.py install for mozlog 00:32:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Running setup.py install for moznetwork 00:32:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Running setup.py install for mozprocess 00:32:52 INFO - Running setup.py install for mozprofile 00:32:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Running setup.py install for mozrunner 00:32:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:32:52 INFO - Running setup.py install for mozscreenshot 00:32:52 INFO - Running setup.py install for moztest 00:32:53 INFO - Running setup.py install for mozversion 00:32:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:32:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:32:53 INFO - Cleaning up... 00:32:53 INFO - Return code: 0 00:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:53 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 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:53 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 00:32:53 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 00:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:53 INFO - 'HOME': '/Users/cltbld', 00:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:53 INFO - 'LOGNAME': 'cltbld', 00:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:53 INFO - 'MOZ_NO_REMOTE': '1', 00:32:53 INFO - 'NO_EM_RESTART': '1', 00:32:53 INFO - 'PAGER': '/bin/cat', 00:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:53 INFO - 'PWD': '/builds/slave/test', 00:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:53 INFO - 'SHELL': '/bin/bash', 00:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:53 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:53 INFO - 'USER': 'cltbld', 00:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:53 INFO - 'XPC_FLAGS': '0x0', 00:32:53 INFO - 'XPC_SERVICE_NAME': '0', 00:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:32:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-kjS_Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 00:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:32:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-fqbLVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:32:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9Nqhnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 00:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:32:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-jFssRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:32:53 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-J_hFMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-G7tr_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-B034IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-MbkHxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WZ6I3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ba0pkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:32:54 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)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-7VHsfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-IM9HK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-jG5eSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 00:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:32:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9ziAbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:32:55 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)) 00:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:32:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-k19Jk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:32:55 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)) 00:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:32:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-J2bMIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:32:55 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)) 00:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:32:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-90OCGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:32:55 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)) 00:32:55 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)) 00:32:55 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)) 00:32:55 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)) 00:32:55 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)) 00:32:55 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)) 00:32:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:32:55 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 00:32:55 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 00:32:55 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 00:32:55 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 00:32:55 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 00:32:55 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 00:32:57 INFO - Downloading blessings-1.6.tar.gz 00:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:32:57 INFO - Installing collected packages: blessings 00:32:57 INFO - Running setup.py install for blessings 00:32:58 INFO - Successfully installed blessings 00:32:58 INFO - Cleaning up... 00:32:58 INFO - Return code: 0 00:32:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:32:58 INFO - Reading from file tmpfile_stdout 00:32:58 INFO - Current package versions: 00:32:58 INFO - blessings == 1.6 00:32:58 INFO - blobuploader == 1.2.4 00:32:58 INFO - docopt == 0.6.1 00:32:58 INFO - functools32 == 3.2.3-2 00:32:58 INFO - jsonschema == 2.5.1 00:32:58 INFO - manifestparser == 1.1 00:32:58 INFO - mozInstall == 1.12 00:32:58 INFO - mozcrash == 0.17 00:32:58 INFO - mozdebug == 0.1 00:32:58 INFO - mozdevice == 0.48 00:32:58 INFO - mozfile == 1.2 00:32:58 INFO - mozhttpd == 0.7 00:32:58 INFO - mozinfo == 0.9 00:32:58 INFO - mozleak == 0.1 00:32:58 INFO - mozlog == 3.2 00:32:58 INFO - moznetwork == 0.27 00:32:58 INFO - mozprocess == 0.23 00:32:58 INFO - mozprofile == 0.28 00:32:58 INFO - mozrunner == 6.12 00:32:58 INFO - mozscreenshot == 0.1 00:32:58 INFO - mozsystemmonitor == 0.3 00:32:58 INFO - moztest == 0.7 00:32:58 INFO - mozversion == 1.4 00:32:58 INFO - psutil == 3.1.1 00:32:58 INFO - requests == 1.2.3 00:32:58 INFO - wsgiref == 0.1.2 00:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:58 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 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:58 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 00:32:58 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 00:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:58 INFO - 'HOME': '/Users/cltbld', 00:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:58 INFO - 'LOGNAME': 'cltbld', 00:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:58 INFO - 'MOZ_NO_REMOTE': '1', 00:32:58 INFO - 'NO_EM_RESTART': '1', 00:32:58 INFO - 'PAGER': '/bin/cat', 00:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:58 INFO - 'PWD': '/builds/slave/test', 00:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:58 INFO - 'SHELL': '/bin/bash', 00:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:58 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:58 INFO - 'USER': 'cltbld', 00:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:58 INFO - 'XPC_FLAGS': '0x0', 00:32:58 INFO - 'XPC_SERVICE_NAME': '0', 00:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:32:58 INFO - Cleaning up... 00:32:58 INFO - Return code: 0 00:32:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c754a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc749d77880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, '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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 00:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:32:58 INFO - 'HOME': '/Users/cltbld', 00:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:32:58 INFO - 'LOGNAME': 'cltbld', 00:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:32:58 INFO - 'MOZ_NO_REMOTE': '1', 00:32:58 INFO - 'NO_EM_RESTART': '1', 00:32:58 INFO - 'PAGER': '/bin/cat', 00:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:32:58 INFO - 'PWD': '/builds/slave/test', 00:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:32:58 INFO - 'SHELL': '/bin/bash', 00:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:32:58 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:32:58 INFO - 'USER': 'cltbld', 00:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:32:58 INFO - 'XPC_FLAGS': '0x0', 00:32:58 INFO - 'XPC_SERVICE_NAME': '0', 00:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:32:58 INFO - Cleaning up... 00:32:58 INFO - Return code: 0 00:32:58 INFO - Running post-action listener: _resource_record_post_action 00:32:58 INFO - Running post-action listener: _start_resource_monitoring 00:32:58 INFO - Starting resource monitoring. 00:32:58 INFO - ##### 00:32:58 INFO - ##### Finished create-virtualenv step (success) 00:32:58 INFO - ##### 00:32:58 INFO - ##### 00:32:58 INFO - ##### Running install step. 00:32:58 INFO - ##### 00:32:58 INFO - Running pre-action listener: _resource_record_pre_action 00:32:58 INFO - Running main action method: install 00:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:32:59 INFO - Reading from file tmpfile_stdout 00:32:59 INFO - Detecting whether we're running mozinstall >=1.0... 00:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:32:59 INFO - Reading from file tmpfile_stdout 00:32:59 INFO - Output received: 00:32:59 INFO - Usage: mozinstall [options] installer 00:32:59 INFO - Options: 00:32:59 INFO - -h, --help show this help message and exit 00:32:59 INFO - -d DEST, --destination=DEST 00:32:59 INFO - Directory to install application into. [default: 00:32:59 INFO - "/builds/slave/test"] 00:32:59 INFO - --app=APP Application being installed. [default: firefox] 00:32:59 INFO - mkdir: /builds/slave/test/build/application 00:32:59 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'] 00:32:59 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 00:33:33 INFO - Reading from file tmpfile_stdout 00:33:33 INFO - Output received: 00:33:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:33:33 INFO - Running post-action listener: _resource_record_post_action 00:33:33 INFO - ##### 00:33:33 INFO - ##### Finished install step (success) 00:33:33 INFO - ##### 00:33:33 INFO - ##### 00:33:33 INFO - ##### Running run-tests step. 00:33:33 INFO - ##### 00:33:33 INFO - Running pre-action listener: _resource_record_pre_action 00:33:33 INFO - Running main action method: run_tests 00:33:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:33:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:33:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:33:33 INFO - 2016-07-10 00:33:33.779 screenresolution[1646:8137] starting screenresolution argv=screenresolution get 00:33:33 INFO - 2016-07-10 00:33:33.781 screenresolution[1646:8137] Display 0: 1600x1200x32@60 00:33:33 INFO - 2016-07-10 00:33:33.788 screenresolution[1647:8139] starting screenresolution argv=screenresolution list 00:33:33 INFO - Available Modes on Display 0 00:33:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:33:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:33:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:33:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:33:34 INFO - Intel Iris: 00:33:34 INFO - Chipset Model: Intel Iris 00:33:34 INFO - Type: GPU 00:33:34 INFO - Bus: Built-In 00:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 00:33:34 INFO - Vendor: Intel (0x8086) 00:33:34 INFO - Device ID: 0x0a2e 00:33:34 INFO - Revision ID: 0x0009 00:33:34 INFO - Displays: 00:33:34 INFO - Display: 00:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:33:34 INFO - Display Serial Number: 1600x1200 60 00:33:34 INFO - Main Display: Yes 00:33:34 INFO - Mirror: Off 00:33:34 INFO - Online: Yes 00:33:34 INFO - Rotation: Supported 00:33:34 INFO - Return code: 0 00:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:33:34 INFO - Python 2.7.3 00:33:34 INFO - Return code: 0 00:33:34 INFO - grabbing minidump binary from tooltool 00:33: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')]} 00:33:34 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 0x10c6b8d30>, '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 0x10c41ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6b0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:33:34 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 00:33:34 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 00:33:34 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 00:33:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:33:34 INFO - Return code: 0 00:33:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 00:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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 00:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xxHTZFpIxe/Render', 00:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:33:34 INFO - 'HOME': '/Users/cltbld', 00:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:33:34 INFO - 'LOGNAME': 'cltbld', 00:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:33:34 INFO - 'MOZ_NO_REMOTE': '1', 00:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:33:34 INFO - 'NO_EM_RESTART': '1', 00:33:34 INFO - 'PAGER': '/bin/cat', 00:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:33:34 INFO - 'PWD': '/builds/slave/test', 00:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:33:34 INFO - 'SHELL': '/bin/bash', 00:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WDPwBmTfuQ/Listeners', 00:33:34 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 00:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:33:34 INFO - 'USER': 'cltbld', 00:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:33:34 INFO - 'XPC_FLAGS': '0x0', 00:33:34 INFO - 'XPC_SERVICE_NAME': '0', 00:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:33:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 00:33:34 INFO - mozversion application_buildid: 20160709233726 00:33:34 INFO - mozversion application_changeset: c22afe017b530907be3bea3d1e1e91ba8e33fac7 00:33:34 INFO - mozversion application_display_name: Nightly 00:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:33:34 INFO - mozversion application_name: Firefox 00:33:34 INFO - mozversion application_remotingname: firefox 00:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 00:33:34 INFO - mozversion application_vendor: Mozilla 00:33:34 INFO - mozversion application_version: 50.0a1 00:33:34 INFO - mozversion platform_buildid: 20160709233726 00:33:34 INFO - mozversion platform_changeset: c22afe017b530907be3bea3d1e1e91ba8e33fac7 00:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 00:33:34 INFO - mozversion platform_version: 50.0a1 00:33:34 INFO - using testdate: 1468136014 00:33:34 INFO - actual date: 1468136014 00:33:34 INFO - starting webserver on 'localhost:49272' 00:33:34 INFO - SUITE-START | Running 2 tests 00:33:34 INFO - TEST-START | dromaeo_css 00:33:34 INFO - operating with platform_type : mac_ 00:33:34 INFO - Initialising browser for dromaeo_css test... 00:33:34 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpUOlrWR/profile http://localhost:49272/getInfo.html) 00:33:36 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 00:33:36 INFO - PROCESS | 1657 | colorDepth:24 00:33:36 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 00:33:36 INFO - PROCESS | 1657 | __metrics 00:33:36 INFO - PROCESS | 1657 | 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" 00:33:36 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:33:37 INFO - TEST-INFO | 1657: exit 0 00:33:37 INFO - Browser initialized. 00:33:37 INFO - Running cycle 1/1 for dromaeo_css test... 00:33:37 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpUOlrWR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:33:49 INFO - PROCESS | 1660 | RSS: Main: 243478528 00:33:49 INFO - PROCESS | 1660 | 00:34:40 INFO - PROCESS | 1660 | 1468136080458 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] 00:36:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 00:36:52 INFO - PROCESS | 1660 | RSS: Main: 474378240 00:36:52 INFO - PROCESS | 1660 | 00:39:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 00:39:56 INFO - PROCESS | 1660 | RSS: Main: 382345216 00:39:56 INFO - PROCESS | 1660 | 00:42:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 00:42:59 INFO - PROCESS | 1660 | RSS: Main: 371998720 00:42:59 INFO - PROCESS | 1660 | 00:46:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 00:46:02 INFO - PROCESS | 1660 | RSS: Main: 375672832 00:46:02 INFO - PROCESS | 1660 | 00:49:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 00:49:06 INFO - PROCESS | 1660 | RSS: Main: 343306240 00:49:06 INFO - PROCESS | 1660 | 00:52:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 00:52:10 INFO - PROCESS | 1660 | RSS: Main: 386461696 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | __start_tp_report 00:52:10 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 00:52:10 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 00:52:10 INFO - PROCESS | 1660 | |i|pagename|runs| 00:52:10 INFO - PROCESS | 1660 | |0;dojo.html;3316.5191047298144;3326.683965023322;3346.2972775717108;3369.696727294543;3387.7797943133696;5515.448455154483;5533.889322213558;5628.183913332575;5628.577856660751;5655.773769049239;4605.9769701151445;4786.784594693238;5595.720213989304;5676.687782171989;5679.488846003859;4995.625328100389;5006.249062640597;5045.747712614373;5080.517350851663;5204.713740744258;4265.936010959838;5155.510226528474;5162.664426812264;5186.662866913639;5190.818321358754;5066.189409694432;5081.898362032775;5112.590992720573;5144.382674079097;5153.613478989072;4517.254652982254;5136.563392111675;5148.922766158511;5151.253068305112;5152.227165925104;5041.823536176216;5045.924311135336;5106.770195341219;5110.795568177268;5119.718415487147;5566.86082847928;5585.469380408855;5601.271834661508;5655.886882262332;5669.716514174274;6162.537809664248;6163.322034576193;6168.96915515424;6172.012478003543;6176.320604733476;5995.490383317423;6138.846528836815;6162.1681073055015;6167.784127555559;6224.128621992924;5151.922721159186;5769.538436925078;5770.711464426762;5772.365039845614;5776.220210271644;6167.907481387782;6307.5584709070845;6384.7126879290545;6393.264774550893;6407.295197528269;5981.551383646244;6055.878882422327;6066;6077.191093565408;6082.787102451393;5129.179331306973;6210.161628180228;6210.913090209286;6218.347073557302;6220;5983.940160598426;6049.879002419927;6112.938870611326;6135.44780969706;6154.5076393888385;6096.786612468542;6101.024948355287;6134.723937422827;6152.230971128609;6154.323024467305;4473.46318441789;4599.333096701027;4820.084184005098;4901.6323775716255;4916.410030796327;4345.34819777036;4354.063876266618;4416.072624748775;4417.315316126005;4417.624501917373;5657.660540367591;5674.574406919498;5703.971480142655;5720.77116915327;5779.682117483579;6255.186825712628;6256.843578910563;6260.342664020212;6271.62370257786;6274.466670333073;5577.024020796425;5635.464630860061;5642.576806739511;5718.456746609066;5721.5708821838525;5353.304070470814;5363.570914326844;5374.704391258519;5378.7041712705395;5401.135818269058;6295.276043254993;6300.117983482408;6337;6355.745770169234;6369.681515924185;6082.4525792677105;6110.083487476914;6147.0779383092895;6231.594946328474;6270.4670103039925;6310.368963103653;6348.619082855043;6370.58591191571;6375;6378.936210637834;6066.241719785027;6086.847828804315;6091.543134264859;6098.359672234352;6105.114758359924;5869.970650146722;6027.186329845415;6044.274687037584;6182.319944806158;6232.909240882918;5905.527557795452;5943.435373639497;5948.851278718067;5952.523798096228;5961.046232602763;5931.4068593142065;5943;5946.762129514771;5956.106584012433;5961.135635332939;5814.5639077068545;5833.737481813413;5835.095560188178;5835.241418615553;5841.853953651193;5950.196723442453;5965;5974.342822289458;5975.014122669711;5986.2517185351835;5740.741666624928;5789.218455508452;5800.535957123504;5823;5874.530037596896 00:52:10 INFO - PROCESS | 1660 | |1;ext.html;476.7485350953746;512.8256392826439;526.4051621667516;526.7787529237718;536.4153073150268;40503.59496405035;41904.37143442851;41977.3703394449;42060.78969605151;44064.89837754058;43056.923576910514;43363.78318108413;43463.565364346345;43511.78244108775;43606.563934360645;8718.997315308725;8811.73564793054;9757.853632195523;9900.50497475127;9920.45437500937;8845.469271843678;8926.196642302191;8936.776580585472;11713.47289371976;11907.642770716893;12078.637640870787;12141.57504487343;12160.39198040099;12163.756724865498;12165.391730413488;11849.526018959274;11906.940465297705;11920.344381059038;11921.165518413714;11925.045996320274;8576.39965202436;10023.548940297704;11594.768104637944;11816.645500634908;12111.878881211163;40845.59154408448;41123.971900702425;41213.58786412128;41260;41337.37993930093;3510.666486683761;3540.5751309842726;3592.802395868226;3622.565292164957;3724.7206459515646;31772.682273177204;32053.71785128592;32504.83747581247;33398.49902251468;34048.82975585131;3347.129746265964;3353.782004169721;3382.813945233011;3486.860525578974;3495.563054618173;543226;554582;555983;556611.9999999959;557889.4211057879;35421.64578354235;36053.639463605556;66430.66784666142;68993.65503172552;70080.6495967517;37524.43713344302;37646.43530347047;37747.62252377496;37750.62249377526;37767.866963991124;12013.519459221534;15496.070235785888;15515.069095854285;15521.611959701098;15531.767023494656;11809.763804724029;12914.09601327898;13027.609171724864;13044.673883152807;13064.150830196008;7684.5773482459;8021.117677055519;8120.309773669304;8199.95900020496;8326.375521835766;3452.1369657585606;3471.20162362658;3479.7912125272564;3492.738044646662;3505.8772942946875;951.5908159491347;964.9807003859884;970.9465979371062;977.4037836919615;1025.610268098118;30170;30415.543766843515;30593.847030765148;30906.381872362428;31166.532502012487;982.9705108846746;1092.63396762084;1122.724932391553;1130.8529891114267;1172.7302720374362;15989;16002;16100;16167.353305867633;16174.10793978204;741238;744551;746940;747704;750395;59570.40429595735;60042.09936850951;60250.98745062817;60303.79392412127;60323.69838150781;1270.0728468218254;1414.094979213325;1429.8498657641005;1446.6094154577995;1454.1347898000672;30936.69063309338;31152.376952461276;31748.57461268209;31794.364112717154;32009.198620207157;48979.75510122427;49302.75348623377;49655.503444965085;49677.509674709814;49820.750896245285;467004;467274;467963;470020.6981371693;470075;27651.44697106087;27778.86110569434;28347.858260708563;28614.713852861205;29622.963196288027;48788.75605621949;49185.26222106743;49798.50201498083;49927;50545.24182137197;38644.420333695576;40270.59729402669;40605.1878962425;41186;41193.58806412017;39008.609913900495;39890.80054599708;41066.76799696014;41069.97325066897;41555.58444415638;38894.02764930899;39199;39923.60076399315;40214;40226.79886600548;34252;35020.64979350174;35176;35917;35998.64001359953;23473.76526234716;25770.871145644152;25965.68556570019;26034.31382351119;26189.607155892296 00:52:10 INFO - PROCESS | 1660 | |2;jquery.html;384.7479900665064;390.1436347217857;394.88548320986934;401.8955071681362;409.61700809742894;19624.509387265327;20518.692219616718;21363.252286170005;21374.358769236907;21435.678464823002;21064.368068957956;21113.049912753962;21421.892890535564;21988.34034978949;22050.11799528017;5697.8005769797965;6173.197484327054;6179.691015449232;6187.783427580036;6190.628562286277;4210.2842516772225;4451.977740111295;5991.640501569898;6525.771597994071;6879.484038697093;6543.2148142222995;6853.520253582251;6856.760013399532;6857.142857142857;6872.037914691922;5861.794837180678;6794.898076528856;6829.556078854884;6844.041834143224;6863.931360686404;5881.353051164411;5946.316173640001;5961.135635332853;5962.284525856881;5977.940220597825;26918.32704182411;26957.865210674016;27035;27086.32284192891;27204.31989200286;2267.965980510294;2287.2795069553204;2292.6561015847587;2339.6022676145094;3011.3525591997604;7111.964440177818;7129.215786263507;9949.353292035994;13720.353557573055;16906.66186676272;1914.9617007659908;1924.9326273580357;1961.695937129747;1969.9212031518723;2043.5197728533783;99025.02924912264;118137;120488.39755801253;120940.39529802382;121690.99999999911;10834.729131771768;11070.667879963614;11318.189089745765;11386.544538218379;11536.942315288537;13712.040157188902;13908.652283692787;13918;13942.024058316023;13955.441782328797;7580.924190758132;8752.124787521198;8915.197632213003;8967.89254525186;9022.233110185707;9030.232430243372;9083.863742043875;9103.81792364149;9207.309451791143;9222.53887305632;5689.715514224272;6417.101605775195;6717.865642687118;6824.863502729918;6826.488013399015;1288.5490078472646;1749.8950062996262;1812.9002904840097;1875.9812401875806;1890.6596812573869;867.7743786615399;1008.5663164839043;1035.9689209323733;1040.458961340114;1051.9316244444087;23022.654660179775;23466;23472.413189670053;23498.060077596703;23526.411839703804;964.2478866484014;965.4255717847869;1023.3655133817081;1025.144004756037;1042.8852826189113;10457.529411176516;11881.465334059989;12389.318587477776;12479.126461147633;12492.81260781089;591284;614382;619671;619959;636241;73363.63318183375;87550;119586;120521;121490;1015.0559979219397;1015.441507171053;1031.1750599520203;1038.5741845843168;1040.1003132290514;20168.89915550413;20496.385108446175;20503;20558.38324850257;20596.485087872923;35117.64882351144;47136.1145554174;50853;51975.48024519706;52729.736351317995;71679;73258;76763;76949.84575231488;77339;6490.1562796838;7376;8099.473534220257;8238.83522329562;8312.25189732927;13884.72230555363;13929.721405571629;14006.649833754238;14059.648508787363;14108.29458527049;11768.882311177122;11956.820647690118;11995.940020299842;12067.396630168632;12114;11726.765464690829;11763.53394131692;11781.587644432404;11820.172587918763;11994.040476761664;12002.1598488105;12007.629568552038;12059.397030148633;12113.33376664292;12113.454894529943;8140.063892652421;8176.713815016446;8254.298384637133;8283.213094755989;8416.70541531068;10919.344839309666;11054.22620416563;11154;11177.720556985814;11186.664400068012 00:52:10 INFO - PROCESS | 1660 | |3;mootools.html;1584.9049057056577;1610.7181243282425;1734.470986349163;1849.9167537460812;1974.8815071095726;8580.442271252372;8876.467411955287;9030.50332231727;9037.096290370959;9047.050059743731;7755.922440775591;8027.678892844279;8033.236842499968;8164.306033987104;8171.550564718938;2320.570694421529;2408.169181632334;2410.4576470294132;2412.2039726890084;2417.5890098683267;2132.264368792764;2289.7710228977053;2372.5847976604114;2415.4202991282054;2415.818813588979;2017.3342796877068;2110.177030957928;2187.212603462752;2393.2222027840935;2394.437307232799;2093.089506064859;2115.291377388569;2213.922512712047;2296.2766728480474;2306.5617532668907;2380.6905102336586;2417.782399584046;2424.7575242475787;2425.0421083672027;2434.1237154624323;8816.735497935073;8908.777280567972;8914.777130571723;8930.732078037669;8960.8655870162;5287.762050707727;5313.07021271276;5367.35591728995;5455.454454554552;5460.918086228709;3481.582210134775;3529.94705079424;3600.945985810215;3662.176010397666;3664.450332450127;1060.5545670818274;1081.2701426537135;1083.3229231730168;1103.2553026706942;1103.4041617526525;11593.188476806627;11823.11326650496;11826.940865295619;11831.822522662167;11833;4655.743934083658;4773.66584339093;4797.668862736656;4858.6356023297685;4932.21084626458;10081.899181008243;10100.696979090639;10109.797804043877;10109.848352274721;10119.08928196451;5392.218128371364;5399.973000134974;5402.108652072443;5408.080626293539;5416.539594134542;3581.301646179022;3772.7924964126687;4549.909001819945;4936.333594964633;4947.604191664658;3962.8612998544913;4058.452108965311;4135.152293779768;4142.482189726285;4144.564820693844;1163.9185257032095;1252.8598974932768;1259.5276771210797;1287.8518970318346;1301.1997621462733;787.2285160542585;868.9876294117408;881.1496905486237;897.6723495924072;983.6409710455632;5863.149843272787;5983.311919129356;6115.1744514490865;6116.541259405562;6262.185915830913;917.4220241247971;957.4686049242598;979.4515071559807;1005.2711783956719;1030.294248439821;3940.389239667856;4051.1492586556565;4099.85650502231;4183.581641835853;4199.580041995776;10478.738031549272;10729.248952573245;10738.73153171177;10806.891931080745;10856.891431085745;10735.463226838627;10763.623273185402;10767.892321076846;10768.78462430747;10777.515011824487;7694.769156925301;8123.309518690978;8161.918380816234;8263.421560490708;8264.380171487162;5822.82531524055;6153.446189842935;6162;6181.35095814933;6227.813165604858;9784.853227201455;9882.357646752938;10169.949150254497;10179.134773544332;10195.082442580204;9319.021502742115;9986.450745209084;10126.341787783771;10262.743431413975;10278.691639250834;7047.7180912762915;7451.40388768908;7652.540847549165;7654.8086297843;7776.572288524186;8255.752327430188;8361.874571881548;8398.706045288385;8436.662533498591;8448.788780280542;7552.66013029426;7597.924020759721;7642.541447512945;7655.0813902332075;7739.7678069658;7459.888101678371;7467.43994200448;7468.141163766019;7603.847923041619;7624.351930085783;6958.617276049663;7068.82327941785;7074.540154889915;7112.715491380287;7202.71189152428;4347.7173983690955;5076.162433198481;5089.821856235014;5116.30929824469;5200.583953283804;3931.04479099191;5103.897922041612;5146.511081447256;5174.844754657366;5313.867153321198 00:52:10 INFO - PROCESS | 1660 | |4;prototype.html;273.2076976767373;305.2155959184895;312.7701139662349;313.5813688725551;321.9613646362437;4229.682773791963;5037.722925239111;5447.836564903055;5500.614956953015;5619.409961953992;4673;5379.246905433242;5401.378841433237;5404.702741349224;5411.918821217685;3835.4630351750707;3978.1645854370618;3989.3816458448987;4002.839886404555;4017.372963949605;3335.966640333602;3496.5454490916272;3695.1870588470492;4121.464209652741;4132.958670413287;3531.9646803532023;3975.2049590081956;4014.739041962263;4040.2323558523785;4040.5353384360883;3202.343519578481;3914.158455931988;4011.217812526544;4073;4079.755214687128;3422.2299982503987;3443.328550932569;3516.8593256269974;3533.293341331744;3577.964220357789;4522.29904364824;5171.276021356975;5180.326557547494;5190.558802501791;5235.973820130875;4442.577955094261;4519.915220347126;4667.533246675339;4702.059588082363;4733.455652599926;5366.436524164946;5581.469760372758;5614.29821272341;5632.324121105453;5667.489925906647;3922;3936.645701886844;3949.723519353646;3951.288768021755;3984.1035766952496;5001.9249711254;5714.857128571777;5937.52499800015;5950.375210602824;6018.909716354215;3969.4442778011103;4004.2191772604647;4017.991004497751;4031.1534577738416;4042.7776472293717;4083.2854250506043;4101.487314085739;4101.794910254475;4102.753834769864;4104.671626269891;4533.954660453419;4682.274247491644;4708.198621820012;4717.646176536773;4737.59730422911;4724.456687480983;5428.212909128154;5462.453754624506;5692.430756924275;5777.971110144423;1361.9989307858716;1374.587623712882;1386.7573174694387;1394.3376895974534;1396.2669598460889;817.4114637461018;857.3312816003527;865.485036403339;871.7864123289835;874.1608056265928;3926.6269704378037;4110.301248787713;4429.180601588652;4444.555544445594;4457.353683715908;4229.577042295746;4753;4920.43990290976;4929.3135241293585;4937.925931111036;3999.4200840878493;4056.533498647634;4422.977885110554;4438.892775057473;4460.77696115193;2632.526145293827;2636.6176904348763;3000.78994470385;3055.6027716396725;3061.5101583746496;4990.351254336985;5864.970675146597;6001.099835024694;6005.219321488178;6069.787557435468;5127.846164615067;5175.861310511681;5874.882502350015;5959.195508606283;5961.5230781537375;5035.370578677665;5247.553957913544;5298.655587386807;6005.2793664758765;6065.908136535378;5808.912866307093;5814.651120932757;5851.531877449879;5860.443257890493;5868.383819699041;4923.655344125877;5437.619366644297;5477.589180811535;5767.625104368202;5767.942320576741;5715.942840571707;6019.518438524995;6022.8795424090395;6095.207623008981;6102.0541816018585;3269.558609587675;3723.8696645617274;3849.557300910431;3997.1206334607505;4011.567218223821;5501.477359650827;5629.240052592848;5788.739506722123;5808.273965754281;5858.502027327725;4693.68576798497;4910.165271903714;5585.1901474286815;5624.240727501735;5641.4922656959425;4829.879253018703;4882.218844984927;5132.46109158533;5289.814856480005;5358.22305765654;5490.023591506974;5492.780288788404;5525.038611292933;5529.723513824373;5539.058360078716;4622.375979242759;4625.83809566677;5371.516563509303;5569.359523654832;5642.802501912413;4408.933865992077;4641.86074417768;5445.074337362737;5517.282753242052;5584.441555844383 00:52:10 INFO - PROCESS | 1660 | |5;yui.html;756.25131120191;771.5447885747408;805.919408059194;808.2442915873662;851.6593362654941;15192.924035379821;15891.44379946702;16593.91703041486;16631.584210394714;16735.16324183792;14915;15156.24218789059;15161.924190379033;15162.696746065072;15172.924135379308;714.1287629092499;737.3548145372799;878.569500944539;892.0455113029061;901.4861528928514;896.919277265046;909.6770646420524;911.9316051296179;911.9772005699878;913.3058875254787;975.1418751498671;975.8389865672156;977.081543349253;979.0845559401982;979.603260679426;759.1876691939655;935.854942483916;966.3332300712486;968.9176420004308;970.5486948568914;718.6047673779452;719.989200161998;720.344486517272;720.8378114924152;722.1586851318208;15385.230738463144;15392.846071539254;15393.076415415111;15400.22998850053;15420.306086226146;13728.65678358039;13766.65583360414;13833;13918.234497102656;14043.1574105554;12538.659536684112;12542.372881355896;12744.745105097845;12749.235045897183;12824.679383015406;4485.730856148609;4550.023022423336;4550.7724613769515;4555.726656400593;4648.535146485358;104661;106549.46725266323;106699.46650266777;107171.46414267957;107374.46312768386;10999.23005389631;11113.777724445466;11139.944300278446;11163.553457861595;11628.53485860556;21747.347579572637;21754.912254387218;21755;21790.128394864343;21809.781902181094;7815.53106813593;7900.486468379537;7914.722984695508;8039.799005024921;8061.355091592659;3752.81235938202;4429.7785110744335;4437.1569401814195;4451.443569553806;4507.661925355612;3496.1609213788506;3613;3615.891523254307;3616.4936908832783;3622.9818850906104;484.4186975629259;663.426136392027;672.1631568697004;673.0207548017622;673.9797806065826;615.4368752591419;679.1544527063837;687.9484038697118;697.4141720954321;715.8926161075881;7957.607418701704;8140.674373025131;9401;9725.951370243103;9754;670.808819486444;685.5030103175258;691.2707094015861;691.5850489706237;696.4776417686735;4754.429468463806;4785.449673287547;4862.7036512652685;4886.413630364308;4891.144049791273;96463;98848;145943;155649.2217538905;158736.20631896998;134461;135126.3243683795;136788;136882;139276;115035.27447088133;117896;118514;120102;122463.77536224524;8375.916240837758;8694.130586941255;8792.42849214799;8852.203301702877;9468.431894086101;61775.691121545904;62645.68677156585;63981.36018639754;65618.68762624625;66040;78251.6087419582;78700;79441;80533;84614;11938.64184074479;12293.078019148421;12391.62825115248;12467.438965246405;12517.812232816334;13473;15024.849751502345;15360.539183824503;16007.599810004378;16023.679526409172;13888.305584720925;14162.088118104337;14254.287285635884;14544.27278636085;14766.557003289918;14068.593140686258;14293;14659.193744344164;14941.477048303257;15210.391584336503;13520.256385898476;13597.660058498617;13811.447542098605;13911.652208694459;13930.512432064828;10144;11667.766644667228;11874.109441791727;11960.820587691365;12040.899632620103;11255.66233013011;11553.653390398302;11736.354500502553;11825.64523064275;11906.821397678868 00:52:10 INFO - PROCESS | 1660 | __end_tp_report 00:52:10 INFO - PROCESS | 1660 | __start_cc_report 00:52:10 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2409 00:52:10 INFO - PROCESS | 1660 | __end_cc_report 00:52:10 INFO - PROCESS | 1660 | __startTimestamp1468137130246__endTimestamp 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | ------- Summary: start ------- 00:52:10 INFO - PROCESS | 1660 | Number of tests: 6 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5645.68 Median:5835.10 stddev:664.04 (11.4%) stddev-sans-first:640.45 00:52:10 INFO - PROCESS | 1660 | Values: 3316.5 3326.7 3346.3 3369.7 3387.8 5515.4 5533.9 5628.2 5628.6 5655.8 4606.0 4786.8 5595.7 5676.7 5679.5 4995.6 5006.2 5045.7 5080.5 5204.7 4265.9 5155.5 5162.7 5186.7 5190.8 5066.2 5081.9 5112.6 5144.4 5153.6 4517.3 5136.6 5148.9 5151.3 5152.2 5041.8 5045.9 5106.8 5110.8 5119.7 5566.9 5585.5 5601.3 5655.9 5669.7 6162.5 6163.3 6169.0 6172.0 6176.3 5995.5 6138.8 6162.2 6167.8 6224.1 5151.9 5769.5 5770.7 5772.4 5776.2 6167.9 6307.6 6384.7 6393.3 6407.3 5981.6 6055.9 6066.0 6077.2 6082.8 5129.2 6210.2 6210.9 6218.3 6220.0 5983.9 6049.9 6112.9 6135.4 6154.5 6096.8 6101.0 6134.7 6152.2 6154.3 4473.5 4599.3 4820.1 4901.6 4916.4 4345.3 4354.1 4416.1 4417.3 4417.6 5657.7 5674.6 5704.0 5720.8 5779.7 6255.2 6256.8 6260.3 6271.6 6274.5 5577.0 5635.5 5642.6 5718.5 5721.6 5353.3 5363.6 5374.7 5378.7 5401.1 6295.3 6300.1 6337.0 6355.7 6369.7 6082.5 6110.1 6147.1 6231.6 6270.5 6310.4 6348.6 6370.6 6375.0 6378.9 6066.2 6086.8 6091.5 6098.4 6105.1 5870.0 6027.2 6044.3 6182.3 6232.9 5905.5 5943.4 5948.9 5952.5 5961.0 5931.4 5943.0 5946.8 5956.1 5961.1 5814.6 5833.7 5835.1 5835.2 5841.9 5950.2 5965.0 5974.3 5975.0 5986.3 5740.7 5789.2 5800.5 5823.0 5874.5 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71459.67 Median:37749.12 stddev:161132.92 (426.9%) stddev-sans-first:161496.80 00:52:10 INFO - PROCESS | 1660 | Values: 476.7 512.8 526.4 526.8 536.4 40503.6 41904.4 41977.4 42060.8 44064.9 43056.9 43363.8 43463.6 43511.8 43606.6 8719.0 8811.7 9757.9 9900.5 9920.5 8845.5 8926.2 8936.8 11713.5 11907.6 12078.6 12141.6 12160.4 12163.8 12165.4 11849.5 11906.9 11920.3 11921.2 11925.0 8576.4 10023.5 11594.8 11816.6 12111.9 40845.6 41124.0 41213.6 41260.0 41337.4 3510.7 3540.6 3592.8 3622.6 3724.7 31772.7 32053.7 32504.8 33398.5 34048.8 3347.1 3353.8 3382.8 3486.9 3495.6 543226.0 554582.0 555983.0 556612.0 557889.4 35421.6 36053.6 66430.7 68993.7 70080.6 37524.4 37646.4 37747.6 37750.6 37767.9 12013.5 15496.1 15515.1 15521.6 15531.8 11809.8 12914.1 13027.6 13044.7 13064.2 7684.6 8021.1 8120.3 8200.0 8326.4 3452.1 3471.2 3479.8 3492.7 3505.9 951.6 965.0 970.9 977.4 1025.6 30170.0 30415.5 30593.8 30906.4 31166.5 983.0 1092.6 1122.7 1130.9 1172.7 15989.0 16002.0 16100.0 16167.4 16174.1 741238.0 744551.0 746940.0 747704.0 750395.0 59570.4 60042.1 60251.0 60303.8 60323.7 1270.1 1414.1 1429.8 1446.6 1454.1 30936.7 31152.4 31748.6 31794.4 32009.2 48979.8 49302.8 49655.5 49677.5 49820.8 467004.0 467274.0 467963.0 470020.7 470075.0 27651.4 27778.9 28347.9 28614.7 29623.0 48788.8 49185.3 49798.5 49927.0 50545.2 38644.4 40270.6 40605.2 41186.0 41193.6 39008.6 39890.8 41066.8 41070.0 41555.6 38894.0 39199.0 39923.6 40214.0 40226.8 34252.0 35020.6 35176.0 35917.0 35998.6 23473.8 25770.9 25965.7 26034.3 26189.6 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35076.59 Median:12902.80 stddev:102082.82 (791.2%) stddev-sans-first:102335.96 00:52:10 INFO - PROCESS | 1660 | Values: 384.7 390.1 394.9 401.9 409.6 19624.5 20518.7 21363.3 21374.4 21435.7 21064.4 21113.0 21421.9 21988.3 22050.1 5697.8 6173.2 6179.7 6187.8 6190.6 4210.3 4452.0 5991.6 6525.8 6879.5 6543.2 6853.5 6856.8 6857.1 6872.0 5861.8 6794.9 6829.6 6844.0 6863.9 5881.4 5946.3 5961.1 5962.3 5977.9 26918.3 26957.9 27035.0 27086.3 27204.3 2268.0 2287.3 2292.7 2339.6 3011.4 7112.0 7129.2 9949.4 13720.4 16906.7 1915.0 1924.9 1961.7 1969.9 2043.5 99025.0 118137.0 120488.4 120940.4 121691.0 10834.7 11070.7 11318.2 11386.5 11536.9 13712.0 13908.7 13918.0 13942.0 13955.4 7580.9 8752.1 8915.2 8967.9 9022.2 9030.2 9083.9 9103.8 9207.3 9222.5 5689.7 6417.1 6717.9 6824.9 6826.5 1288.5 1749.9 1812.9 1876.0 1890.7 867.8 1008.6 1036.0 1040.5 1051.9 23022.7 23466.0 23472.4 23498.1 23526.4 964.2 965.4 1023.4 1025.1 1042.9 10457.5 11881.5 12389.3 12479.1 12492.8 591284.0 614382.0 619671.0 619959.0 636241.0 73363.6 87550.0 119586.0 120521.0 121490.0 1015.1 1015.4 1031.2 1038.6 1040.1 20168.9 20496.4 20503.0 20558.4 20596.5 35117.6 47136.1 50853.0 51975.5 52729.7 71679.0 73258.0 76763.0 76949.8 77339.0 6490.2 7376.0 8099.5 8238.8 8312.3 13884.7 13929.7 14006.6 14059.6 14108.3 11768.9 11956.8 11995.9 12067.4 12114.0 11726.8 11763.5 11781.6 11820.2 11994.0 12002.2 12007.6 12059.4 12113.3 12113.5 8140.1 8176.7 8254.3 8283.2 8416.7 10919.3 11054.2 11154.0 11177.7 11186.7 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5744.65 Median:4828.15 stddev:3216.54 (66.6%) stddev-sans-first:3210.38 00:52:10 INFO - PROCESS | 1660 | Values: 1584.9 1610.7 1734.5 1849.9 1974.9 8580.4 8876.5 9030.5 9037.1 9047.1 7755.9 8027.7 8033.2 8164.3 8171.6 2320.6 2408.2 2410.5 2412.2 2417.6 2132.3 2289.8 2372.6 2415.4 2415.8 2017.3 2110.2 2187.2 2393.2 2394.4 2093.1 2115.3 2213.9 2296.3 2306.6 2380.7 2417.8 2424.8 2425.0 2434.1 8816.7 8908.8 8914.8 8930.7 8960.9 5287.8 5313.1 5367.4 5455.5 5460.9 3481.6 3529.9 3600.9 3662.2 3664.5 1060.6 1081.3 1083.3 1103.3 1103.4 11593.2 11823.1 11826.9 11831.8 11833.0 4655.7 4773.7 4797.7 4858.6 4932.2 10081.9 10100.7 10109.8 10109.8 10119.1 5392.2 5400.0 5402.1 5408.1 5416.5 3581.3 3772.8 4549.9 4936.3 4947.6 3962.9 4058.5 4135.2 4142.5 4144.6 1163.9 1252.9 1259.5 1287.9 1301.2 787.2 869.0 881.1 897.7 983.6 5863.1 5983.3 6115.2 6116.5 6262.2 917.4 957.5 979.5 1005.3 1030.3 3940.4 4051.1 4099.9 4183.6 4199.6 10478.7 10729.2 10738.7 10806.9 10856.9 10735.5 10763.6 10767.9 10768.8 10777.5 7694.8 8123.3 8161.9 8263.4 8264.4 5822.8 6153.4 6162.0 6181.4 6227.8 9784.9 9882.4 10169.9 10179.1 10195.1 9319.0 9986.5 10126.3 10262.7 10278.7 7047.7 7451.4 7652.5 7654.8 7776.6 8255.8 8361.9 8398.7 8436.7 8448.8 7552.7 7597.9 7642.5 7655.1 7739.8 7459.9 7467.4 7468.1 7603.8 7624.4 6958.6 7068.8 7074.5 7112.7 7202.7 4347.7 5076.2 5089.8 5116.3 5200.6 3931.0 5103.9 5146.5 5174.8 5313.9 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4477.49 Median:4791.44 stddev:1376.76 (28.7%) stddev-sans-first:1343.97 00:52:10 INFO - PROCESS | 1660 | Values: 273.2 305.2 312.8 313.6 322.0 4229.7 5037.7 5447.8 5500.6 5619.4 4673.0 5379.2 5401.4 5404.7 5411.9 3835.5 3978.2 3989.4 4002.8 4017.4 3336.0 3496.5 3695.2 4121.5 4133.0 3532.0 3975.2 4014.7 4040.2 4040.5 3202.3 3914.2 4011.2 4073.0 4079.8 3422.2 3443.3 3516.9 3533.3 3578.0 4522.3 5171.3 5180.3 5190.6 5236.0 4442.6 4519.9 4667.5 4702.1 4733.5 5366.4 5581.5 5614.3 5632.3 5667.5 3922.0 3936.6 3949.7 3951.3 3984.1 5001.9 5714.9 5937.5 5950.4 6018.9 3969.4 4004.2 4018.0 4031.2 4042.8 4083.3 4101.5 4101.8 4102.8 4104.7 4534.0 4682.3 4708.2 4717.6 4737.6 4724.5 5428.2 5462.5 5692.4 5778.0 1362.0 1374.6 1386.8 1394.3 1396.3 817.4 857.3 865.5 871.8 874.2 3926.6 4110.3 4429.2 4444.6 4457.4 4229.6 4753.0 4920.4 4929.3 4937.9 3999.4 4056.5 4423.0 4438.9 4460.8 2632.5 2636.6 3000.8 3055.6 3061.5 4990.4 5865.0 6001.1 6005.2 6069.8 5127.8 5175.9 5874.9 5959.2 5961.5 5035.4 5247.6 5298.7 6005.3 6065.9 5808.9 5814.7 5851.5 5860.4 5868.4 4923.7 5437.6 5477.6 5767.6 5767.9 5715.9 6019.5 6022.9 6095.2 6102.1 3269.6 3723.9 3849.6 3997.1 4011.6 5501.5 5629.2 5788.7 5808.3 5858.5 4693.7 4910.2 5585.2 5624.2 5641.5 4829.9 4882.2 5132.5 5289.8 5358.2 5490.0 5492.8 5525.0 5529.7 5539.1 4622.4 4625.8 5371.5 5569.4 5642.8 4408.9 4641.9 5445.1 5517.3 5584.4 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:24642.33 Median:3616.19 stddev:38837.58 (1074.0%) stddev-sans-first:38905.12 00:52:10 INFO - PROCESS | 1660 | Values: 756.3 771.5 805.9 808.2 851.7 15192.9 15891.4 16593.9 16631.6 16735.2 14915.0 15156.2 15161.9 15162.7 15172.9 714.1 737.4 878.6 892.0 901.5 896.9 909.7 911.9 912.0 913.3 975.1 975.8 977.1 979.1 979.6 759.2 935.9 966.3 968.9 970.5 718.6 720.0 720.3 720.8 722.2 15385.2 15392.8 15393.1 15400.2 15420.3 13728.7 13766.7 13833.0 13918.2 14043.2 12538.7 12542.4 12744.7 12749.2 12824.7 4485.7 4550.0 4550.8 4555.7 4648.5 104661.0 106549.5 106699.5 107171.5 107374.5 10999.2 11113.8 11139.9 11163.6 11628.5 21747.3 21754.9 21755.0 21790.1 21809.8 7815.5 7900.5 7914.7 8039.8 8061.4 3752.8 4429.8 4437.2 4451.4 4507.7 3496.2 3613.0 3615.9 3616.5 3623.0 484.4 663.4 672.2 673.0 674.0 615.4 679.2 687.9 697.4 715.9 7957.6 8140.7 9401.0 9726.0 9754.0 670.8 685.5 691.3 691.6 696.5 4754.4 4785.4 4862.7 4886.4 4891.1 96463.0 98848.0 145943.0 155649.2 158736.2 134461.0 135126.3 136788.0 136882.0 139276.0 115035.3 117896.0 118514.0 120102.0 122463.8 8375.9 8694.1 8792.4 8852.2 9468.4 61775.7 62645.7 63981.4 65618.7 66040.0 78251.6 78700.0 79441.0 80533.0 84614.0 11938.6 12293.1 12391.6 12467.4 12517.8 13473.0 15024.8 15360.5 16007.6 16023.7 13888.3 14162.1 14254.3 14544.3 14766.6 14068.6 14293.0 14659.2 14941.5 15210.4 13520.3 13597.7 13811.4 13911.7 13930.5 10144.0 11667.8 11874.1 11960.8 12040.9 11255.7 11553.7 11736.4 11825.6 11906.8 00:52:10 INFO - PROCESS | 1660 | -------- Summary: end -------- 00:52:10 INFO - PROCESS | 1660 | 00:52:10 INFO - TEST-INFO | 1660: exit 0 00:52:10 INFO - TEST-OK | dromaeo_css | took 1116530ms 00:52:10 INFO - TEST-START | kraken 00:52:10 INFO - operating with platform_type : mac_ 00:52:10 INFO - Initialising browser for kraken test... 00:52:10 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpOpLaka/profile http://localhost:49272/getInfo.html) 00:52:13 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 00:52:13 INFO - PROCESS | 1665 | colorDepth:24 00:52:13 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 00:52:13 INFO - PROCESS | 1665 | __metrics 00:52:13 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:52:13 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:52:13 INFO - TEST-INFO | 1665: exit 0 00:52:13 INFO - Browser initialized. 00:52:13 INFO - Running cycle 1/1 for kraken test... 00:52:13 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpOpLaka/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:52:25 INFO - PROCESS | 1666 | RSS: Main: 243752960 00:52:25 INFO - PROCESS | 1666 | 00:52:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 00:52:29 INFO - PROCESS | 1666 | RSS: Main: 294301696 00:52:29 INFO - PROCESS | 1666 | 00:52:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 00:52:32 INFO - PROCESS | 1666 | RSS: Main: 304177152 00:52:32 INFO - PROCESS | 1666 | 00:52:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 00:52:36 INFO - PROCESS | 1666 | RSS: Main: 388890624 00:52:36 INFO - PROCESS | 1666 | 00:52:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 00:52:39 INFO - PROCESS | 1666 | RSS: Main: 289038336 00:52:39 INFO - PROCESS | 1666 | 00:52:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:52:42 INFO - PROCESS | 1666 | RSS: Main: 293031936 00:52:42 INFO - PROCESS | 1666 | 00:52:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 00:52:45 INFO - PROCESS | 1666 | RSS: Main: 331292672 00:52:45 INFO - PROCESS | 1666 | 00:52:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 00:52:49 INFO - PROCESS | 1666 | RSS: Main: 331382784 00:52:49 INFO - PROCESS | 1666 | 00:52:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 00:52:52 INFO - PROCESS | 1666 | RSS: Main: 331190272 00:52:52 INFO - PROCESS | 1666 | 00:52:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:52:54 INFO - PROCESS | 1666 | RSS: Main: 311476224 00:52:54 INFO - PROCESS | 1666 | 00:52:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:52:57 INFO - PROCESS | 1666 | RSS: Main: 318263296 00:52:57 INFO - PROCESS | 1666 | 00:52:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:52:59 INFO - PROCESS | 1666 | RSS: Main: 322301952 00:52:59 INFO - PROCESS | 1666 | 00:53:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:53:03 INFO - PROCESS | 1666 | RSS: Main: 323604480 00:53:03 INFO - PROCESS | 1666 | 00:53:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:53:06 INFO - PROCESS | 1666 | RSS: Main: 324935680 00:53:06 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 00:53:08 INFO - PROCESS | 1666 | RSS: Main: 368754688 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | __start_tp_report 00:53:08 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 00:53:08 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 00:53:08 INFO - PROCESS | 1666 | |i|pagename|runs| 00:53:08 INFO - PROCESS | 1666 | |0;ai-astar;100;111;113;112;113;112;113;112;112;113 00:53:08 INFO - PROCESS | 1666 | |1;audio-beat-detection;141;106;107;161;113;106;128;105;140;107 00:53:08 INFO - PROCESS | 1666 | |2;audio-dft;183;184;184;182;185;180;180;181;182;185 00:53:08 INFO - PROCESS | 1666 | |3;audio-fft;79;73;58;109;57;57;61;66;97;61 00:53:08 INFO - PROCESS | 1666 | |4;audio-oscillator;85;86;92;71;72;90;127;96;69;102 00:53:08 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;87;85;91;85;87;85;89;86;88;85 00:53:08 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;93;95;99;97;93;93;94;93;92 00:53:08 INFO - PROCESS | 1666 | |7;imaging-desaturate;85;85;85;85;85;85;85;84;83;84 00:53:08 INFO - PROCESS | 1666 | |8;json-parse-financial;54;53;55;56;53;55;54;53;55;53 00:53:08 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;66;74;81;73;73;71;71;76;71 00:53:08 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;84;84;85;86;85;81;88;86;88;105 00:53:08 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;138;137;137;138;143;149;132;135;151;138 00:53:08 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;163;168;162;163;164;165;164;168;168;171 00:53:08 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;67;68;69;67;67;66;65;66;66;66 00:53:08 INFO - PROCESS | 1666 | __end_tp_report 00:53:08 INFO - PROCESS | 1666 | __start_cc_report 00:53:08 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1985 00:53:08 INFO - PROCESS | 1666 | __end_cc_report 00:53:08 INFO - PROCESS | 1666 | __startTimestamp1468137188834__endTimestamp 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | ------- Summary: start ------- 00:53:08 INFO - PROCESS | 1666 | Number of tests: 14 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.50 stddev:3.96 (3.5%) stddev-sans-first:0.71 00:53:08 INFO - PROCESS | 1666 | Values: 100.0 111.0 113.0 112.0 113.0 112.0 113.0 112.0 112.0 113.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:120.50 stddev:19.92 (16.5%) stddev-sans-first:19.82 00:53:08 INFO - PROCESS | 1666 | Values: 141.0 106.0 107.0 161.0 113.0 106.0 128.0 105.0 140.0 107.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.90 (1.0%) stddev-sans-first:2.01 00:53:08 INFO - PROCESS | 1666 | Values: 183.0 184.0 184.0 182.0 185.0 180.0 180.0 181.0 182.0 185.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:71.80 Median:63.50 stddev:18.16 (28.6%) stddev-sans-first:19.07 00:53:08 INFO - PROCESS | 1666 | Values: 79.0 73.0 58.0 109.0 57.0 57.0 61.0 66.0 97.0 61.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:85.50 stddev:17.35 (20.3%) stddev-sans-first:18.34 00:53:08 INFO - PROCESS | 1666 | Values: 85.0 86.0 92.0 71.0 72.0 90.0 127.0 96.0 69.0 102.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:2.04 (2.3%) stddev-sans-first:2.17 00:53:08 INFO - PROCESS | 1666 | Values: 87.0 85.0 91.0 85.0 87.0 85.0 89.0 86.0 88.0 85.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.50 stddev:2.32 (2.5%) stddev-sans-first:2.29 00:53:08 INFO - PROCESS | 1666 | Values: 97.0 93.0 95.0 99.0 97.0 93.0 93.0 94.0 93.0 92.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 00:53:08 INFO - PROCESS | 1666 | Values: 85.0 85.0 85.0 85.0 85.0 85.0 85.0 84.0 83.0 84.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.10 (2.0%) stddev-sans-first:1.17 00:53:08 INFO - PROCESS | 1666 | Values: 54.0 53.0 55.0 56.0 53.0 55.0 54.0 53.0 55.0 53.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.00 stddev:4.44 (6.1%) stddev-sans-first:4.11 00:53:08 INFO - PROCESS | 1666 | Values: 66.0 66.0 74.0 81.0 73.0 73.0 71.0 71.0 76.0 71.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.50 stddev:6.58 (7.7%) stddev-sans-first:6.88 00:53:08 INFO - PROCESS | 1666 | Values: 84.0 84.0 85.0 86.0 85.0 81.0 88.0 86.0 88.0 105.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:138.00 stddev:6.05 (4.4%) stddev-sans-first:6.38 00:53:08 INFO - PROCESS | 1666 | Values: 138.0 137.0 137.0 138.0 143.0 149.0 132.0 135.0 151.0 138.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.50 stddev:2.95 (1.8%) stddev-sans-first:2.98 00:53:08 INFO - PROCESS | 1666 | Values: 163.0 168.0 162.0 163.0 164.0 165.0 164.0 168.0 168.0 171.0 00:53:08 INFO - PROCESS | 1666 | 00:53:08 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 00:53:08 INFO - PROCESS | 1666 | Values: 67.0 68.0 69.0 67.0 67.0 66.0 65.0 66.0 66.0 66.0 00:53:08 INFO - PROCESS | 1666 | -------- Summary: end -------- 00:53:08 INFO - PROCESS | 1666 | 00:53:09 INFO - TEST-INFO | 1666: exit 0 00:53:09 INFO - TEST-OK | kraken | took 58738ms 00:53:09 INFO - SUITE-END | took 1175s 00:53:09 INFO - Completed test suite (00:19:35) 00:53:09 INFO - javascript benchmark 00:53:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3316.5191047298144, 3326.683965023322, 3346.2972775717108, 3369.696727294543, 3387.7797943133696, 5515.448455154483, 5533.889322213558, 5628.183913332575, 5628.577856660751, 5655.773769049239, 4605.9769701151445, 4786.784594693238, 5595.720213989304, 5676.687782171989, 5679.488846003859, 4995.625328100389, 5006.249062640597, 5045.747712614373, 5080.517350851663, 5204.713740744258, 4265.936010959838, 5155.510226528474, 5162.664426812264, 5186.662866913639, 5190.818321358754, 5066.189409694432, 5081.898362032775, 5112.590992720573, 5144.382674079097, 5153.613478989072, 4517.254652982254, 5136.563392111675, 5148.922766158511, 5151.253068305112, 5152.227165925104, 5041.823536176216, 5045.924311135336, 5106.770195341219, 5110.795568177268, 5119.718415487147, 5566.86082847928, 5585.469380408855, 5601.271834661508, 5655.886882262332, 5669.716514174274, 6162.537809664248, 6163.322034576193, 6168.96915515424, 6172.012478003543, 6176.320604733476, 5995.490383317423, 6138.846528836815, 6162.1681073055015, 6167.784127555559, 6224.128621992924, 5151.922721159186, 5769.538436925078, 5770.711464426762, 5772.365039845614, 5776.220210271644, 6167.907481387782, 6307.5584709070845, 6384.7126879290545, 6393.264774550893, 6407.295197528269, 5981.551383646244, 6055.878882422327, 6066.0, 6077.191093565408, 6082.787102451393, 5129.179331306973, 6210.161628180228, 6210.913090209286, 6218.347073557302, 6220.0, 5983.940160598426, 6049.879002419927, 6112.938870611326, 6135.44780969706, 6154.5076393888385, 6096.786612468542, 6101.024948355287, 6134.723937422827, 6152.230971128609, 6154.323024467305, 4473.46318441789, 4599.333096701027, 4820.084184005098, 4901.6323775716255, 4916.410030796327, 4345.34819777036, 4354.063876266618, 4416.072624748775, 4417.315316126005, 4417.624501917373, 5657.660540367591, 5674.574406919498, 5703.971480142655, 5720.77116915327, 5779.682117483579, 6255.186825712628, 6256.843578910563, 6260.342664020212, 6271.62370257786, 6274.466670333073, 5577.024020796425, 5635.464630860061, 5642.576806739511, 5718.456746609066, 5721.5708821838525, 5353.304070470814, 5363.570914326844, 5374.704391258519, 5378.7041712705395, 5401.135818269058, 6295.276043254993, 6300.117983482408, 6337.0, 6355.745770169234, 6369.681515924185, 6082.4525792677105, 6110.083487476914, 6147.0779383092895, 6231.594946328474, 6270.4670103039925, 6310.368963103653, 6348.619082855043, 6370.58591191571, 6375.0, 6378.936210637834, 6066.241719785027, 6086.847828804315, 6091.543134264859, 6098.359672234352, 6105.114758359924, 5869.970650146722, 6027.186329845415, 6044.274687037584, 6182.319944806158, 6232.909240882918, 5905.527557795452, 5943.435373639497, 5948.851278718067, 5952.523798096228, 5961.046232602763, 5931.4068593142065, 5943.0, 5946.762129514771, 5956.106584012433, 5961.135635332939, 5814.5639077068545, 5833.737481813413, 5835.095560188178, 5835.241418615553, 5841.853953651193, 5950.196723442453, 5965.0, 5974.342822289458, 5975.014122669711, 5986.2517185351835, 5740.741666624928, 5789.218455508452, 5800.535957123504, 5823.0, 5874.530037596896], "value": 5602.753616774084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.7485350953746, 512.8256392826439, 526.4051621667516, 526.7787529237718, 536.4153073150268, 40503.59496405035, 41904.37143442851, 41977.3703394449, 42060.78969605151, 44064.89837754058, 43056.923576910514, 43363.78318108413, 43463.565364346345, 43511.78244108775, 43606.563934360645, 8718.997315308725, 8811.73564793054, 9757.853632195523, 9900.50497475127, 9920.45437500937, 8845.469271843678, 8926.196642302191, 8936.776580585472, 11713.47289371976, 11907.642770716893, 12078.637640870787, 12141.57504487343, 12160.39198040099, 12163.756724865498, 12165.391730413488, 11849.526018959274, 11906.940465297705, 11920.344381059038, 11921.165518413714, 11925.045996320274, 8576.39965202436, 10023.548940297704, 11594.768104637944, 11816.645500634908, 12111.878881211163, 40845.59154408448, 41123.971900702425, 41213.58786412128, 41260.0, 41337.37993930093, 3510.666486683761, 3540.5751309842726, 3592.802395868226, 3622.565292164957, 3724.7206459515646, 31772.682273177204, 32053.71785128592, 32504.83747581247, 33398.49902251468, 34048.82975585131, 3347.129746265964, 3353.782004169721, 3382.813945233011, 3486.860525578974, 3495.563054618173, 543226.0, 554582.0, 555983.0, 556611.9999999959, 557889.4211057879, 35421.64578354235, 36053.639463605556, 66430.66784666142, 68993.65503172552, 70080.6495967517, 37524.43713344302, 37646.43530347047, 37747.62252377496, 37750.62249377526, 37767.866963991124, 12013.519459221534, 15496.070235785888, 15515.069095854285, 15521.611959701098, 15531.767023494656, 11809.763804724029, 12914.09601327898, 13027.609171724864, 13044.673883152807, 13064.150830196008, 7684.5773482459, 8021.117677055519, 8120.309773669304, 8199.95900020496, 8326.375521835766, 3452.1369657585606, 3471.20162362658, 3479.7912125272564, 3492.738044646662, 3505.8772942946875, 951.5908159491347, 964.9807003859884, 970.9465979371062, 977.4037836919615, 1025.610268098118, 30170.0, 30415.543766843515, 30593.847030765148, 30906.381872362428, 31166.532502012487, 982.9705108846746, 1092.63396762084, 1122.724932391553, 1130.8529891114267, 1172.7302720374362, 15989.0, 16002.0, 16100.0, 16167.353305867633, 16174.10793978204, 741238.0, 744551.0, 746940.0, 747704.0, 750395.0, 59570.40429595735, 60042.09936850951, 60250.98745062817, 60303.79392412127, 60323.69838150781, 1270.0728468218254, 1414.094979213325, 1429.8498657641005, 1446.6094154577995, 1454.1347898000672, 30936.69063309338, 31152.376952461276, 31748.57461268209, 31794.364112717154, 32009.198620207157, 48979.75510122427, 49302.75348623377, 49655.503444965085, 49677.509674709814, 49820.750896245285, 467004.0, 467274.0, 467963.0, 470020.6981371693, 470075.0, 27651.44697106087, 27778.86110569434, 28347.858260708563, 28614.713852861205, 29622.963196288027, 48788.75605621949, 49185.26222106743, 49798.50201498083, 49927.0, 50545.24182137197, 38644.420333695576, 40270.59729402669, 40605.1878962425, 41186.0, 41193.58806412017, 39008.609913900495, 39890.80054599708, 41066.76799696014, 41069.97325066897, 41555.58444415638, 38894.02764930899, 39199.0, 39923.60076399315, 40214.0, 40226.79886600548, 34252.0, 35020.64979350174, 35176.0, 35917.0, 35998.64001359953, 23473.76526234716, 25770.871145644152, 25965.68556570019, 26034.31382351119, 26189.607155892296], "value": 19521.16856742587, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.7479900665064, 390.1436347217857, 394.88548320986934, 401.8955071681362, 409.61700809742894, 19624.509387265327, 20518.692219616718, 21363.252286170005, 21374.358769236907, 21435.678464823002, 21064.368068957956, 21113.049912753962, 21421.892890535564, 21988.34034978949, 22050.11799528017, 5697.8005769797965, 6173.197484327054, 6179.691015449232, 6187.783427580036, 6190.628562286277, 4210.2842516772225, 4451.977740111295, 5991.640501569898, 6525.771597994071, 6879.484038697093, 6543.2148142222995, 6853.520253582251, 6856.760013399532, 6857.142857142857, 6872.037914691922, 5861.794837180678, 6794.898076528856, 6829.556078854884, 6844.041834143224, 6863.931360686404, 5881.353051164411, 5946.316173640001, 5961.135635332853, 5962.284525856881, 5977.940220597825, 26918.32704182411, 26957.865210674016, 27035.0, 27086.32284192891, 27204.31989200286, 2267.965980510294, 2287.2795069553204, 2292.6561015847587, 2339.6022676145094, 3011.3525591997604, 7111.964440177818, 7129.215786263507, 9949.353292035994, 13720.353557573055, 16906.66186676272, 1914.9617007659908, 1924.9326273580357, 1961.695937129747, 1969.9212031518723, 2043.5197728533783, 99025.02924912264, 118137.0, 120488.39755801253, 120940.39529802382, 121690.99999999911, 10834.729131771768, 11070.667879963614, 11318.189089745765, 11386.544538218379, 11536.942315288537, 13712.040157188902, 13908.652283692787, 13918.0, 13942.024058316023, 13955.441782328797, 7580.924190758132, 8752.124787521198, 8915.197632213003, 8967.89254525186, 9022.233110185707, 9030.232430243372, 9083.863742043875, 9103.81792364149, 9207.309451791143, 9222.53887305632, 5689.715514224272, 6417.101605775195, 6717.865642687118, 6824.863502729918, 6826.488013399015, 1288.5490078472646, 1749.8950062996262, 1812.9002904840097, 1875.9812401875806, 1890.6596812573869, 867.7743786615399, 1008.5663164839043, 1035.9689209323733, 1040.458961340114, 1051.9316244444087, 23022.654660179775, 23466.0, 23472.413189670053, 23498.060077596703, 23526.411839703804, 964.2478866484014, 965.4255717847869, 1023.3655133817081, 1025.144004756037, 1042.8852826189113, 10457.529411176516, 11881.465334059989, 12389.318587477776, 12479.126461147633, 12492.81260781089, 591284.0, 614382.0, 619671.0, 619959.0, 636241.0, 73363.63318183375, 87550.0, 119586.0, 120521.0, 121490.0, 1015.0559979219397, 1015.441507171053, 1031.1750599520203, 1038.5741845843168, 1040.1003132290514, 20168.89915550413, 20496.385108446175, 20503.0, 20558.38324850257, 20596.485087872923, 35117.64882351144, 47136.1145554174, 50853.0, 51975.48024519706, 52729.736351317995, 71679.0, 73258.0, 76763.0, 76949.84575231488, 77339.0, 6490.1562796838, 7376.0, 8099.473534220257, 8238.83522329562, 8312.25189732927, 13884.72230555363, 13929.721405571629, 14006.649833754238, 14059.648508787363, 14108.29458527049, 11768.882311177122, 11956.820647690118, 11995.940020299842, 12067.396630168632, 12114.0, 11726.765464690829, 11763.53394131692, 11781.587644432404, 11820.172587918763, 11994.040476761664, 12002.1598488105, 12007.629568552038, 12059.397030148633, 12113.33376664292, 12113.454894529943, 8140.063892652421, 8176.713815016446, 8254.298384637133, 8283.213094755989, 8416.70541531068, 10919.344839309666, 11054.22620416563, 11154.0, 11177.720556985814, 11186.664400068012], "value": 9956.577606775487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1584.9049057056577, 1610.7181243282425, 1734.470986349163, 1849.9167537460812, 1974.8815071095726, 8580.442271252372, 8876.467411955287, 9030.50332231727, 9037.096290370959, 9047.050059743731, 7755.922440775591, 8027.678892844279, 8033.236842499968, 8164.306033987104, 8171.550564718938, 2320.570694421529, 2408.169181632334, 2410.4576470294132, 2412.2039726890084, 2417.5890098683267, 2132.264368792764, 2289.7710228977053, 2372.5847976604114, 2415.4202991282054, 2415.818813588979, 2017.3342796877068, 2110.177030957928, 2187.212603462752, 2393.2222027840935, 2394.437307232799, 2093.089506064859, 2115.291377388569, 2213.922512712047, 2296.2766728480474, 2306.5617532668907, 2380.6905102336586, 2417.782399584046, 2424.7575242475787, 2425.0421083672027, 2434.1237154624323, 8816.735497935073, 8908.777280567972, 8914.777130571723, 8930.732078037669, 8960.8655870162, 5287.762050707727, 5313.07021271276, 5367.35591728995, 5455.454454554552, 5460.918086228709, 3481.582210134775, 3529.94705079424, 3600.945985810215, 3662.176010397666, 3664.450332450127, 1060.5545670818274, 1081.2701426537135, 1083.3229231730168, 1103.2553026706942, 1103.4041617526525, 11593.188476806627, 11823.11326650496, 11826.940865295619, 11831.822522662167, 11833.0, 4655.743934083658, 4773.66584339093, 4797.668862736656, 4858.6356023297685, 4932.21084626458, 10081.899181008243, 10100.696979090639, 10109.797804043877, 10109.848352274721, 10119.08928196451, 5392.218128371364, 5399.973000134974, 5402.108652072443, 5408.080626293539, 5416.539594134542, 3581.301646179022, 3772.7924964126687, 4549.909001819945, 4936.333594964633, 4947.604191664658, 3962.8612998544913, 4058.452108965311, 4135.152293779768, 4142.482189726285, 4144.564820693844, 1163.9185257032095, 1252.8598974932768, 1259.5276771210797, 1287.8518970318346, 1301.1997621462733, 787.2285160542585, 868.9876294117408, 881.1496905486237, 897.6723495924072, 983.6409710455632, 5863.149843272787, 5983.311919129356, 6115.1744514490865, 6116.541259405562, 6262.185915830913, 917.4220241247971, 957.4686049242598, 979.4515071559807, 1005.2711783956719, 1030.294248439821, 3940.389239667856, 4051.1492586556565, 4099.85650502231, 4183.581641835853, 4199.580041995776, 10478.738031549272, 10729.248952573245, 10738.73153171177, 10806.891931080745, 10856.891431085745, 10735.463226838627, 10763.623273185402, 10767.892321076846, 10768.78462430747, 10777.515011824487, 7694.769156925301, 8123.309518690978, 8161.918380816234, 8263.421560490708, 8264.380171487162, 5822.82531524055, 6153.446189842935, 6162.0, 6181.35095814933, 6227.813165604858, 9784.853227201455, 9882.357646752938, 10169.949150254497, 10179.134773544332, 10195.082442580204, 9319.021502742115, 9986.450745209084, 10126.341787783771, 10262.743431413975, 10278.691639250834, 7047.7180912762915, 7451.40388768908, 7652.540847549165, 7654.8086297843, 7776.572288524186, 8255.752327430188, 8361.874571881548, 8398.706045288385, 8436.662533498591, 8448.788780280542, 7552.66013029426, 7597.924020759721, 7642.541447512945, 7655.0813902332075, 7739.7678069658, 7459.888101678371, 7467.43994200448, 7468.141163766019, 7603.847923041619, 7624.351930085783, 6958.617276049663, 7068.82327941785, 7074.540154889915, 7112.715491380287, 7202.71189152428, 4347.7173983690955, 5076.162433198481, 5089.821856235014, 5116.30929824469, 5200.583953283804, 3931.04479099191, 5103.897922041612, 5146.511081447256, 5174.844754657366, 5313.867153321198], "value": 4616.79512739575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.2076976767373, 305.2155959184895, 312.7701139662349, 313.5813688725551, 321.9613646362437, 4229.682773791963, 5037.722925239111, 5447.836564903055, 5500.614956953015, 5619.409961953992, 4673.0, 5379.246905433242, 5401.378841433237, 5404.702741349224, 5411.918821217685, 3835.4630351750707, 3978.1645854370618, 3989.3816458448987, 4002.839886404555, 4017.372963949605, 3335.966640333602, 3496.5454490916272, 3695.1870588470492, 4121.464209652741, 4132.958670413287, 3531.9646803532023, 3975.2049590081956, 4014.739041962263, 4040.2323558523785, 4040.5353384360883, 3202.343519578481, 3914.158455931988, 4011.217812526544, 4073.0, 4079.755214687128, 3422.2299982503987, 3443.328550932569, 3516.8593256269974, 3533.293341331744, 3577.964220357789, 4522.29904364824, 5171.276021356975, 5180.326557547494, 5190.558802501791, 5235.973820130875, 4442.577955094261, 4519.915220347126, 4667.533246675339, 4702.059588082363, 4733.455652599926, 5366.436524164946, 5581.469760372758, 5614.29821272341, 5632.324121105453, 5667.489925906647, 3922.0, 3936.645701886844, 3949.723519353646, 3951.288768021755, 3984.1035766952496, 5001.9249711254, 5714.857128571777, 5937.52499800015, 5950.375210602824, 6018.909716354215, 3969.4442778011103, 4004.2191772604647, 4017.991004497751, 4031.1534577738416, 4042.7776472293717, 4083.2854250506043, 4101.487314085739, 4101.794910254475, 4102.753834769864, 4104.671626269891, 4533.954660453419, 4682.274247491644, 4708.198621820012, 4717.646176536773, 4737.59730422911, 4724.456687480983, 5428.212909128154, 5462.453754624506, 5692.430756924275, 5777.971110144423, 1361.9989307858716, 1374.587623712882, 1386.7573174694387, 1394.3376895974534, 1396.2669598460889, 817.4114637461018, 857.3312816003527, 865.485036403339, 871.7864123289835, 874.1608056265928, 3926.6269704378037, 4110.301248787713, 4429.180601588652, 4444.555544445594, 4457.353683715908, 4229.577042295746, 4753.0, 4920.43990290976, 4929.3135241293585, 4937.925931111036, 3999.4200840878493, 4056.533498647634, 4422.977885110554, 4438.892775057473, 4460.77696115193, 2632.526145293827, 2636.6176904348763, 3000.78994470385, 3055.6027716396725, 3061.5101583746496, 4990.351254336985, 5864.970675146597, 6001.099835024694, 6005.219321488178, 6069.787557435468, 5127.846164615067, 5175.861310511681, 5874.882502350015, 5959.195508606283, 5961.5230781537375, 5035.370578677665, 5247.553957913544, 5298.655587386807, 6005.2793664758765, 6065.908136535378, 5808.912866307093, 5814.651120932757, 5851.531877449879, 5860.443257890493, 5868.383819699041, 4923.655344125877, 5437.619366644297, 5477.589180811535, 5767.625104368202, 5767.942320576741, 5715.942840571707, 6019.518438524995, 6022.8795424090395, 6095.207623008981, 6102.0541816018585, 3269.558609587675, 3723.8696645617274, 3849.557300910431, 3997.1206334607505, 4011.567218223821, 5501.477359650827, 5629.240052592848, 5788.739506722123, 5808.273965754281, 5858.502027327725, 4693.68576798497, 4910.165271903714, 5585.1901474286815, 5624.240727501735, 5641.4922656959425, 4829.879253018703, 4882.218844984927, 5132.46109158533, 5289.814856480005, 5358.22305765654, 5490.023591506974, 5492.780288788404, 5525.038611292933, 5529.723513824373, 5539.058360078716, 4622.375979242759, 4625.83809566677, 5371.516563509303, 5569.359523654832, 5642.802501912413, 4408.933865992077, 4641.86074417768, 5445.074337362737, 5517.282753242052, 5584.441555844383], "value": 4043.185954100211, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.25131120191, 771.5447885747408, 805.919408059194, 808.2442915873662, 851.6593362654941, 15192.924035379821, 15891.44379946702, 16593.91703041486, 16631.584210394714, 16735.16324183792, 14915.0, 15156.24218789059, 15161.924190379033, 15162.696746065072, 15172.924135379308, 714.1287629092499, 737.3548145372799, 878.569500944539, 892.0455113029061, 901.4861528928514, 896.919277265046, 909.6770646420524, 911.9316051296179, 911.9772005699878, 913.3058875254787, 975.1418751498671, 975.8389865672156, 977.081543349253, 979.0845559401982, 979.603260679426, 759.1876691939655, 935.854942483916, 966.3332300712486, 968.9176420004308, 970.5486948568914, 718.6047673779452, 719.989200161998, 720.344486517272, 720.8378114924152, 722.1586851318208, 15385.230738463144, 15392.846071539254, 15393.076415415111, 15400.22998850053, 15420.306086226146, 13728.65678358039, 13766.65583360414, 13833.0, 13918.234497102656, 14043.1574105554, 12538.659536684112, 12542.372881355896, 12744.745105097845, 12749.235045897183, 12824.679383015406, 4485.730856148609, 4550.023022423336, 4550.7724613769515, 4555.726656400593, 4648.535146485358, 104661.0, 106549.46725266323, 106699.46650266777, 107171.46414267957, 107374.46312768386, 10999.23005389631, 11113.777724445466, 11139.944300278446, 11163.553457861595, 11628.53485860556, 21747.347579572637, 21754.912254387218, 21755.0, 21790.128394864343, 21809.781902181094, 7815.53106813593, 7900.486468379537, 7914.722984695508, 8039.799005024921, 8061.355091592659, 3752.81235938202, 4429.7785110744335, 4437.1569401814195, 4451.443569553806, 4507.661925355612, 3496.1609213788506, 3613.0, 3615.891523254307, 3616.4936908832783, 3622.9818850906104, 484.4186975629259, 663.426136392027, 672.1631568697004, 673.0207548017622, 673.9797806065826, 615.4368752591419, 679.1544527063837, 687.9484038697118, 697.4141720954321, 715.8926161075881, 7957.607418701704, 8140.674373025131, 9401.0, 9725.951370243103, 9754.0, 670.808819486444, 685.5030103175258, 691.2707094015861, 691.5850489706237, 696.4776417686735, 4754.429468463806, 4785.449673287547, 4862.7036512652685, 4886.413630364308, 4891.144049791273, 96463.0, 98848.0, 145943.0, 155649.2217538905, 158736.20631896998, 134461.0, 135126.3243683795, 136788.0, 136882.0, 139276.0, 115035.27447088133, 117896.0, 118514.0, 120102.0, 122463.77536224524, 8375.916240837758, 8694.130586941255, 8792.42849214799, 8852.203301702877, 9468.431894086101, 61775.691121545904, 62645.68677156585, 63981.36018639754, 65618.68762624625, 66040.0, 78251.6087419582, 78700.0, 79441.0, 80533.0, 84614.0, 11938.64184074479, 12293.078019148421, 12391.62825115248, 12467.438965246405, 12517.812232816334, 13473.0, 15024.849751502345, 15360.539183824503, 16007.599810004378, 16023.679526409172, 13888.305584720925, 14162.088118104337, 14254.287285635884, 14544.27278636085, 14766.557003289918, 14068.593140686258, 14293.0, 14659.193744344164, 14941.477048303257, 15210.391584336503, 13520.256385898476, 13597.660058498617, 13811.447542098605, 13911.652208694459, 13930.512432064828, 10144.0, 11667.766644667228, 11874.109441791727, 11960.820587691365, 12040.899632620103, 11255.66233013011, 11553.653390398302, 11736.354500502553, 11825.64523064275, 11906.821397678868], "value": 8011.127903286527, "unit": "score"}], "name": "dromaeo_css", "value": 7389.8730100049415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 107.0, 161.0, 113.0, 106.0, 128.0, 105.0, 140.0, 107.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 182.0, 185.0, 180.0, 180.0, 181.0, 182.0, 185.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 109.0, 57.0, 57.0, 61.0, 66.0, 97.0, 61.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 92.0, 71.0, 72.0, 90.0, 127.0, 96.0, 69.0, 102.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 91.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 93.0, 95.0, 99.0, 97.0, 93.0, 93.0, 94.0, 93.0, 92.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 84.0, 83.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 56.0, 53.0, 55.0, 54.0, 53.0, 55.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 74.0, 81.0, 73.0, 73.0, 71.0, 71.0, 76.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 86.0, 85.0, 81.0, 88.0, 86.0, 88.0, 105.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 138.0, 143.0, 149.0, 132.0, 135.0, 151.0, 138.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 162.0, 163.0, 164.0, 165.0, 164.0, 168.0, 168.0, 171.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 67.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1427.5, "alertThreshold": 2.0}]} 00:53:10 INFO - Return code: 0 00:53:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:53:10 INFO - # TBPL SUCCESS # 00:53:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:53:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:53:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:53:10 INFO - 2016-07-10 00:53:10.145 screenresolution[1670:10215] starting screenresolution argv=screenresolution get 00:53:10 INFO - 2016-07-10 00:53:10.147 screenresolution[1670:10215] Display 0: 1600x1200x32@60 00:53:10 INFO - 2016-07-10 00:53:10.153 screenresolution[1671:10218] starting screenresolution argv=screenresolution list 00:53:10 INFO - Available Modes on Display 0 00:53:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:53:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:53:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:53:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:53:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:53:10 INFO - Intel Iris: 00:53:10 INFO - Chipset Model: Intel Iris 00:53:10 INFO - Type: GPU 00:53:10 INFO - Bus: Built-In 00:53:10 INFO - VRAM (Dynamic, Max): 1536 MB 00:53:10 INFO - Vendor: Intel (0x8086) 00:53:10 INFO - Device ID: 0x0a2e 00:53:10 INFO - Revision ID: 0x0009 00:53:10 INFO - Displays: 00:53:10 INFO - Display: 00:53:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:53:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:53:10 INFO - Display Serial Number: 1600x1200 60 00:53:10 INFO - Main Display: Yes 00:53:10 INFO - Mirror: Off 00:53:10 INFO - Online: Yes 00:53:10 INFO - Rotation: Supported 00:53:10 INFO - Return code: 0 00:53:10 INFO - Running post-action listener: _resource_record_post_action 00:53:10 INFO - ##### 00:53:10 INFO - ##### Finished run-tests step (success) 00:53:10 INFO - ##### 00:53:10 INFO - Running post-run listener: _resource_record_post_run 00:53:10 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31297024; Write bytes: 703027712; Read time: 193; Write time: 1989 00:53:10 INFO - TinderboxPrint: CPU usage
53.6% 00:53:10 INFO - TinderboxPrint: I/O read bytes / time
31,297,024 / 193 00:53:10 INFO - TinderboxPrint: I/O write bytes / time
703,027,712 / 1,989 00:53:10 INFO - TinderboxPrint: CPU idle
2,247.6 (46.4%) 00:53:10 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 00:53:10 INFO - TinderboxPrint: CPU user
1,626.1 (33.6%) 00:53:10 INFO - TinderboxPrint: Swap in / out
433,291,264 / 0 00:53:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389612032; Write bytes: 383154176; Read time: 29005; Write time: 858 00:53:10 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30375424; Write bytes: 294740480; Read time: 183; Write time: 1072 00:53:10 INFO - Running post-run listener: _upload_blobber_files 00:53:10 INFO - Blob upload gear active. 00:53:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:53:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:53:11 INFO - (blobuploader) - INFO - Open directory for files ... 00:53:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:53:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:53:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:53:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:53:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:53:12 INFO - (blobuploader) - INFO - Done attempting. 00:53:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:53:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:53:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:53:13 INFO - (blobuploader) - INFO - Done attempting. 00:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:53:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:53:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:53:15 INFO - (blobuploader) - INFO - Done attempting. 00:53:15 INFO - (blobuploader) - INFO - Iteration through files over. 00:53:15 INFO - Return code: 0 00:53:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:53:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:53:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8520804f9924acab061707270fb2c2bc167664a003d578487b35cde3c89ddd19b3147e74119493c03772da32771c99f6188aed05212b1e1c7fe383d0697864f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ffb8d81dc6aad358aade34aef26ec0ab102242b032f0eac73b67477b35f4256db39751751f1b3198e09253f437d38350094fec02c7605d9334f6e593db39e1f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2ad48c617d8f970521bb992e23aa826f6086bc865d6e430a151606fe66834719ef3a20b67859bd5258cb679c5a82ac55a89a8bcbd934d7ba232322255f684cb"} 00:53:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:53:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:53:15 INFO - Contents: 00:53:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8520804f9924acab061707270fb2c2bc167664a003d578487b35cde3c89ddd19b3147e74119493c03772da32771c99f6188aed05212b1e1c7fe383d0697864f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ffb8d81dc6aad358aade34aef26ec0ab102242b032f0eac73b67477b35f4256db39751751f1b3198e09253f437d38350094fec02c7605d9334f6e593db39e1f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2ad48c617d8f970521bb992e23aa826f6086bc865d6e430a151606fe66834719ef3a20b67859bd5258cb679c5a82ac55a89a8bcbd934d7ba232322255f684cb"} 00:53:15 INFO - Running post-run listener: copy_logs_to_upload_dir 00:53:15 INFO - Copying logs to upload dir... 00:53:15 INFO - mkdir: /builds/slave/test/build/upload/logs 00:53:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.370057 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-10 00:53:15.578944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 00:53:15.582492) ========= 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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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/Autoland/sha512/8520804f9924acab061707270fb2c2bc167664a003d578487b35cde3c89ddd19b3147e74119493c03772da32771c99f6188aed05212b1e1c7fe383d0697864f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ffb8d81dc6aad358aade34aef26ec0ab102242b032f0eac73b67477b35f4256db39751751f1b3198e09253f437d38350094fec02c7605d9334f6e593db39e1f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2ad48c617d8f970521bb992e23aa826f6086bc865d6e430a151606fe66834719ef3a20b67859bd5258cb679c5a82ac55a89a8bcbd934d7ba232322255f684cb"} build_url:https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011044 build_url: 'https://queue.taskcluster.net/v1/task/VcaCszy3QIujx4_ZscrS5Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8520804f9924acab061707270fb2c2bc167664a003d578487b35cde3c89ddd19b3147e74119493c03772da32771c99f6188aed05212b1e1c7fe383d0697864f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ffb8d81dc6aad358aade34aef26ec0ab102242b032f0eac73b67477b35f4256db39751751f1b3198e09253f437d38350094fec02c7605d9334f6e593db39e1f3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e2ad48c617d8f970521bb992e23aa826f6086bc865d6e430a151606fe66834719ef3a20b67859bd5258cb679c5a82ac55a89a8bcbd934d7ba232322255f684cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 00:53:15.615729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:53:15.616100) ========= 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.xxHTZFpIxe/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.WDPwBmTfuQ/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005220 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 00:53:15.665973) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 00:53:15.666413) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 00:53:15.666786) ========= ========= Total master_lag: 0.24 =========