builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0337 starttime: 1468425762.77 results: success (0) buildid: 20160713080326 builduid: 4ca9c2faf5224c749be39d6483a3c376 revision: 5df61b441fa4d208272c35aac1f9975c59b5cff8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.769369) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.769969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.770269) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.795361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.797036) ========= 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.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-13 09:02:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-07-13 09:02:42 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.143436 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.963437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:42.963900) ========= 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.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.026165 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.013075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.013433) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5df61b441fa4d208272c35aac1f9975c59b5cff8 --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 5df61b441fa4d208272c35aac1f9975c59b5cff8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-13 09:02:43,097 truncating revision to first 12 chars 2016-07-13 09:02:43,097 Setting DEBUG logging. 2016-07-13 09:02:43,097 attempt 1/10 2016-07-13 09:02:43,097 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5df61b441fa4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 09:02:43,407 unpacking tar archive at: autoland-5df61b441fa4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.584741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.620088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.620405) ========= script_repo_revision: 5df61b441fa4d208272c35aac1f9975c59b5cff8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.620800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.621088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:02:43.639731) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-13 09:02:43.640250) ========= /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.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:02:43 INFO - MultiFileLogger online at 20160713 09:02:43 in /builds/slave/test 09:02:43 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 09:02:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:43 INFO - {'append_to_log': False, 09:02:43 INFO - 'base_work_dir': '/builds/slave/test', 09:02:43 INFO - 'blob_upload_branch': 'Autoland', 09:02:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:43 INFO - 'branch': 'Autoland', 09:02:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:43 INFO - 'config_files': ('talos/mac_config.py',), 09:02:43 INFO - 'default_actions': ('clobber', 09:02:43 INFO - 'read-buildbot-config', 09:02:43 INFO - 'download-and-extract', 09:02:43 INFO - 'populate-webroot', 09:02:43 INFO - 'create-virtualenv', 09:02:43 INFO - 'install', 09:02:43 INFO - 'run-tests'), 09:02:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:43 INFO - 'download_minidump_stackwalk': True, 09:02:43 INFO - 'download_symbols': 'ondemand', 09:02:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:43 INFO - 'installer_path': 'installer.exe', 09:02:43 INFO - 'log_level': 'info', 09:02:43 INFO - 'log_name': 'talos', 09:02:43 INFO - 'log_to_console': True, 09:02:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:02:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:02:43 INFO - 'opt_config_files': (), 09:02:43 INFO - 'pip_index': False, 09:02:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:43 INFO - 'cmd': ('bash', 09:02:43 INFO - '-c', 09:02:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:43 INFO - 'enabled': True, 09:02:43 INFO - 'halt_on_failure': False, 09:02:43 INFO - 'name': 'check_screen_resolution'},), 09:02:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:02:43 INFO - 'cmd': ('bash', 09:02:43 INFO - '-c', 09:02:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:02:43 INFO - 'enabled': True, 09:02:43 INFO - 'halt_on_failure': False, 09:02:43 INFO - 'name': 'check_screen_resolution'},), 09:02:43 INFO - 'run_cmd_checks_enabled': True, 09:02:43 INFO - 'sps_profile': False, 09:02:43 INFO - 'sps_profile_interval': 0, 09:02:43 INFO - 'suite': 'dromaeojs', 09:02:43 INFO - 'system_bits': '32', 09:02:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:43 INFO - 'title': 't-yosemite-r7-0337', 09:02:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:43 INFO - 'use_talos_json': True, 09:02:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:43 INFO - 'work_dir': 'build'} 09:02:43 INFO - ##### 09:02:43 INFO - ##### Running clobber step. 09:02:43 INFO - ##### 09:02:43 INFO - Running pre-action listener: _resource_record_pre_action 09:02:43 INFO - Running main action method: clobber 09:02:43 INFO - rmtree: /builds/slave/test/build 09:02:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:45 INFO - Running post-action listener: _resource_record_post_action 09:02:45 INFO - ##### 09:02:45 INFO - ##### Finished clobber step (success) 09:02:45 INFO - ##### 09:02:45 INFO - ##### 09:02:45 INFO - ##### Running read-buildbot-config step. 09:02:45 INFO - ##### 09:02:45 INFO - Running pre-action listener: _resource_record_pre_action 09:02:45 INFO - Running main action method: read_buildbot_config 09:02:45 INFO - Using buildbot properties: 09:02:45 INFO - { 09:02:45 INFO - "project": "", 09:02:45 INFO - "product": "firefox", 09:02:45 INFO - "script_repo_revision": "production", 09:02:45 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 09:02:45 INFO - "repository": "", 09:02:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 09:02:45 INFO - "buildid": "20160713080326", 09:02:45 INFO - "pgo_build": "False", 09:02:45 INFO - "basedir": "/builds/slave/test", 09:02:45 INFO - "buildnumber": 11, 09:02:45 INFO - "slavename": "t-yosemite-r7-0337", 09:02:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:02:45 INFO - "platform": "yosemite_r7", 09:02:45 INFO - "scheduler": "tests-autoland-macosx64-talos", 09:02:45 INFO - "branch": "autoland", 09:02:45 INFO - "repo_path": "integration/autoland", 09:02:45 INFO - "revision": "5df61b441fa4d208272c35aac1f9975c59b5cff8", 09:02:45 INFO - "stage_platform": "macosx64", 09:02:45 INFO - "builduid": "4ca9c2faf5224c749be39d6483a3c376", 09:02:45 INFO - "slavebuilddir": "test" 09:02:45 INFO - } 09:02:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:02:45 INFO - Running post-action listener: _resource_record_post_action 09:02:45 INFO - ##### 09:02:45 INFO - ##### Finished read-buildbot-config step (success) 09:02:45 INFO - ##### 09:02:45 INFO - ##### 09:02:45 INFO - ##### Running download-and-extract step. 09:02:45 INFO - ##### 09:02:45 INFO - Running pre-action listener: _resource_record_pre_action 09:02:45 INFO - Running main action method: download_and_extract 09:02:45 INFO - mkdir: /builds/slave/test/build/tests 09:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:45 INFO - https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:02:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:02:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:02:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:02:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:02:47 INFO - Downloaded 1199 bytes. 09:02:47 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:02:47 INFO - Using the following test package requirements: 09:02:47 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:02:47 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:02:47 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:02:47 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'jsshell-mac.zip'], 09:02:47 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:02:47 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:02:47 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:02:47 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:02:47 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:02:47 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:02:47 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:02:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:47 INFO - https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:02:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:49 INFO - Downloaded 18822776 bytes. 09:02:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:02:52 INFO - Return code: 0 09:02:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:52 INFO - https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:02:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:02:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:53 INFO - Downloaded 18822776 bytes. 09:02:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:02:56 INFO - Return code: 0 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:56 INFO - https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:02:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:02:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:02:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:02:57 INFO - Downloaded 14403317 bytes. 09:02:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:02:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:02:58 INFO - Return code: 0 09:02: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')]} 09:02:58 INFO - https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:02:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:02:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:02:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:02:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:03:01 INFO - Downloaded 112789415 bytes. 09:03:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:03:01 INFO - mkdir: /builds/slave/test/properties 09:03:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:03:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:03:01 INFO - Contents: 09:03:01 INFO - build_url:https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:03:02 INFO - Running post-action listener: _resource_record_post_action 09:03:02 INFO - Running post-action listener: set_extra_try_arguments 09:03:02 INFO - ##### 09:03:02 INFO - ##### Finished download-and-extract step (success) 09:03:02 INFO - ##### 09:03:02 INFO - ##### 09:03:02 INFO - ##### Running populate-webroot step. 09:03:02 INFO - ##### 09:03:02 INFO - Running pre-action listener: _resource_record_pre_action 09:03:02 INFO - Running main action method: populate_webroot 09:03:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:03:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:03:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:03:02 INFO - '--tppagecycles', 09:03:02 INFO - '7'], 09:03:02 INFO - 'tests': ['tsvgm']}}, 09:03:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:03:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:03:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:03:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:03:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['damp', 'tps']}, 09:03:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'tests': ['damp', 'tps']}, 09:03:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['dromaeo_dom']}, 09:03:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:03:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['basic_compositor_video']}, 09:03:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:03:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['a11yr', 09:03:02 INFO - 'ts_paint', 09:03:02 INFO - 'tpaint', 09:03:02 INFO - 'sessionrestore', 09:03:02 INFO - 'sessionrestore_no_auto_restore', 09:03:02 INFO - 'tabpaint']}, 09:03:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['tsvgx', 09:03:02 INFO - 'tsvgr_opacity', 09:03:02 INFO - 'tart', 09:03:02 INFO - 'tscrollx', 09:03:02 INFO - 'cart']}, 09:03:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:03:02 INFO - 'tsvgr_opacity', 09:03:02 INFO - 'tart', 09:03:02 INFO - 'tscrollx', 09:03:02 INFO - 'cart']}, 09:03:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'talos_options': ['--disable-e10s'], 09:03:02 INFO - 'tests': ['tp5o']}, 09:03:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'tests': ['tp5o']}, 09:03:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'talos_options': ['--disable-e10s', 09:03:02 INFO - '--xperf_path', 09:03:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:02 INFO - 'tests': ['tp5n']}, 09:03:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:02 INFO - 'talos_options': ['--xperf_path', 09:03:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:02 INFO - 'tests': ['tp5n']}}, 09:03:02 INFO - 'talos.zip': {'path': '', 09:03:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:03:02 INFO - Running post-action listener: _resource_record_post_action 09:03:02 INFO - ##### 09:03:02 INFO - ##### Finished populate-webroot step (success) 09:03:02 INFO - ##### 09:03:02 INFO - ##### 09:03:02 INFO - ##### Running create-virtualenv step. 09:03:02 INFO - ##### 09:03:02 INFO - Running pre-action listener: _resource_record_pre_action 09:03:02 INFO - Running main action method: create_virtualenv 09:03:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:03:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:03:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:03:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:03:02 INFO - Using real prefix '/tools/python27' 09:03:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:03:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:03:05 INFO - Installing pip.................done. 09:03:05 INFO - Return code: 0 09:03:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:05 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:05 INFO - 'HOME': '/Users/cltbld', 09:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:05 INFO - 'LOGNAME': 'cltbld', 09:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:05 INFO - 'MOZ_NO_REMOTE': '1', 09:03:05 INFO - 'NO_EM_RESTART': '1', 09:03:05 INFO - 'PAGER': '/bin/cat', 09:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:05 INFO - 'PWD': '/builds/slave/test', 09:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:05 INFO - 'SHELL': '/bin/bash', 09:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:05 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:05 INFO - 'USER': 'cltbld', 09:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:05 INFO - 'XPC_FLAGS': '0x0', 09:03:05 INFO - 'XPC_SERVICE_NAME': '0', 09:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:03:06 INFO - Cleaning up... 09:03:06 INFO - Return code: 0 09:03:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:06 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:06 INFO - 'HOME': '/Users/cltbld', 09:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:06 INFO - 'LOGNAME': 'cltbld', 09:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:06 INFO - 'MOZ_NO_REMOTE': '1', 09:03:06 INFO - 'NO_EM_RESTART': '1', 09:03:06 INFO - 'PAGER': '/bin/cat', 09:03:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:06 INFO - 'PWD': '/builds/slave/test', 09:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:06 INFO - 'SHELL': '/bin/bash', 09:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:06 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:06 INFO - 'USER': 'cltbld', 09:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:06 INFO - 'XPC_FLAGS': '0x0', 09:03:06 INFO - 'XPC_SERVICE_NAME': '0', 09:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:06 INFO - Downloading/unpacking psutil>=3.1.1 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:03:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:09 INFO - Installing collected packages: psutil 09:03:09 INFO - Running setup.py install for psutil 09:03:09 INFO - building 'psutil._psutil_osx' extension 09:03:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:03:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:03:10 INFO - building 'psutil._psutil_posix' extension 09:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:03:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:03:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:03:10 INFO - ^ 09:03:10 INFO - 1 warning generated. 09:03:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:03:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:10 INFO - Successfully installed psutil 09:03:10 INFO - Cleaning up... 09:03:10 INFO - Return code: 0 09:03:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:03: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')]} 09:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03: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')]} 09:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:10 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:03:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:10 INFO - 'HOME': '/Users/cltbld', 09:03:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:10 INFO - 'LOGNAME': 'cltbld', 09:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:10 INFO - 'MOZ_NO_REMOTE': '1', 09:03:10 INFO - 'NO_EM_RESTART': '1', 09:03:10 INFO - 'PAGER': '/bin/cat', 09:03:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:10 INFO - 'PWD': '/builds/slave/test', 09:03:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:10 INFO - 'SHELL': '/bin/bash', 09:03:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:10 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:10 INFO - 'USER': 'cltbld', 09:03:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:10 INFO - 'XPC_FLAGS': '0x0', 09:03:10 INFO - 'XPC_SERVICE_NAME': '0', 09:03:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:13 INFO - Installing collected packages: mozsystemmonitor 09:03:13 INFO - Running setup.py install for mozsystemmonitor 09:03:13 INFO - Successfully installed mozsystemmonitor 09:03:13 INFO - Cleaning up... 09:03:13 INFO - Return code: 0 09:03:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:13 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:13 INFO - 'HOME': '/Users/cltbld', 09:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:13 INFO - 'LOGNAME': 'cltbld', 09:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:13 INFO - 'MOZ_NO_REMOTE': '1', 09:03:13 INFO - 'NO_EM_RESTART': '1', 09:03:13 INFO - 'PAGER': '/bin/cat', 09:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:13 INFO - 'PWD': '/builds/slave/test', 09:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:13 INFO - 'SHELL': '/bin/bash', 09:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:13 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:13 INFO - 'USER': 'cltbld', 09:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:13 INFO - 'XPC_FLAGS': '0x0', 09:03:13 INFO - 'XPC_SERVICE_NAME': '0', 09:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:13 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:16 INFO - warning: no files found matching '*.txt' 09:03:16 INFO - no previously-included directories found matching 'build' 09:03:16 INFO - no previously-included directories found matching 'dist' 09:03:16 INFO - no previously-included directories found matching '.git*' 09:03:16 INFO - Installing collected packages: jsonschema, functools32 09:03:16 INFO - Running setup.py install for functools32 09:03:16 INFO - warning: no files found matching '*.txt' 09:03:16 INFO - no previously-included directories found matching 'build' 09:03:16 INFO - no previously-included directories found matching 'dist' 09:03:16 INFO - no previously-included directories found matching '.git*' 09:03:16 INFO - Successfully installed jsonschema functools32 09:03:16 INFO - Cleaning up... 09:03:16 INFO - Return code: 0 09:03:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:16 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:16 INFO - 'HOME': '/Users/cltbld', 09:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:16 INFO - 'LOGNAME': 'cltbld', 09:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:16 INFO - 'MOZ_NO_REMOTE': '1', 09:03:16 INFO - 'NO_EM_RESTART': '1', 09:03:16 INFO - 'PAGER': '/bin/cat', 09:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:16 INFO - 'PWD': '/builds/slave/test', 09:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:16 INFO - 'SHELL': '/bin/bash', 09:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:16 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:16 INFO - 'USER': 'cltbld', 09:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:16 INFO - 'XPC_FLAGS': '0x0', 09:03:16 INFO - 'XPC_SERVICE_NAME': '0', 09:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:19 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:20 INFO - Downloading docopt-0.6.1.tar.gz 09:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:20 INFO - Running setup.py install for blobuploader 09:03:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:21 INFO - Running setup.py install for requests 09:03:21 INFO - Running setup.py install for docopt 09:03:21 INFO - Successfully installed blobuploader requests docopt 09:03:21 INFO - Cleaning up... 09:03:21 INFO - Return code: 0 09:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:21 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:21 INFO - 'HOME': '/Users/cltbld', 09:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:21 INFO - 'LOGNAME': 'cltbld', 09:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:21 INFO - 'MOZ_NO_REMOTE': '1', 09:03:21 INFO - 'NO_EM_RESTART': '1', 09:03:21 INFO - 'PAGER': '/bin/cat', 09:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:21 INFO - 'PWD': '/builds/slave/test', 09:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:21 INFO - 'SHELL': '/bin/bash', 09:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:21 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:21 INFO - 'USER': 'cltbld', 09:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:21 INFO - 'XPC_FLAGS': '0x0', 09:03:21 INFO - 'XPC_SERVICE_NAME': '0', 09:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-wyVaqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-M3Bv78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-y56hNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-UbuS0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-lWyFH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-l6lAB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-nDl8z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-wk621X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:22 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-rGfKuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-TMFr0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-cUKjog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-dAVjgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-6Dq4rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-fJKDak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mrk4Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-2T4Oqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:23 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-5bDZgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:23 INFO - Running setup.py install for manifestparser 09:03:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:24 INFO - Running setup.py install for mozcrash 09:03:24 INFO - Running setup.py install for mozdebug 09:03:24 INFO - Running setup.py install for mozdevice 09:03:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:24 INFO - Running setup.py install for mozfile 09:03:24 INFO - Running setup.py install for mozhttpd 09:03:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:24 INFO - Running setup.py install for mozinfo 09:03:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:24 INFO - Running setup.py install for mozInstall 09:03:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Running setup.py install for mozleak 09:03:25 INFO - Running setup.py install for mozlog 09:03:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Running setup.py install for moznetwork 09:03:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Running setup.py install for mozprocess 09:03:25 INFO - Running setup.py install for mozprofile 09:03:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:25 INFO - Running setup.py install for mozrunner 09:03:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:26 INFO - Running setup.py install for mozscreenshot 09:03:26 INFO - Running setup.py install for moztest 09:03:26 INFO - Running setup.py install for mozversion 09:03:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:26 INFO - Cleaning up... 09:03:26 INFO - Return code: 0 09:03:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:26 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:26 INFO - 'HOME': '/Users/cltbld', 09:03:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:26 INFO - 'LOGNAME': 'cltbld', 09:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:26 INFO - 'MOZ_NO_REMOTE': '1', 09:03:26 INFO - 'NO_EM_RESTART': '1', 09:03:26 INFO - 'PAGER': '/bin/cat', 09:03:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:26 INFO - 'PWD': '/builds/slave/test', 09:03:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:26 INFO - 'SHELL': '/bin/bash', 09:03:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:26 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:26 INFO - 'USER': 'cltbld', 09:03:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:26 INFO - 'XPC_FLAGS': '0x0', 09:03:26 INFO - 'XPC_SERVICE_NAME': '0', 09:03:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:26 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-RKdpGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:26 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-V7LJF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:26 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ldzDi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-n7BRg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-SuZdeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-vYACZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-BNvy2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-r6QCHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-YYkIKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-Npjmea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xdhna4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:27 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-RwelgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:28 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)) 09:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-NMytLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-AryeVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:28 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)) 09:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-PEWTye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-2ANe9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:28 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-BJgMAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:31 INFO - Downloading blessings-1.6.tar.gz 09:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:31 INFO - Installing collected packages: blessings 09:03:31 INFO - Running setup.py install for blessings 09:03:31 INFO - Successfully installed blessings 09:03:31 INFO - Cleaning up... 09:03:31 INFO - Return code: 0 09:03:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:31 INFO - Reading from file tmpfile_stdout 09:03:31 INFO - Current package versions: 09:03:31 INFO - blessings == 1.6 09:03:31 INFO - blobuploader == 1.2.4 09:03:31 INFO - docopt == 0.6.1 09:03:31 INFO - functools32 == 3.2.3-2 09:03:31 INFO - jsonschema == 2.5.1 09:03:31 INFO - manifestparser == 1.1 09:03:31 INFO - mozInstall == 1.12 09:03:31 INFO - mozcrash == 0.17 09:03:31 INFO - mozdebug == 0.1 09:03:31 INFO - mozdevice == 0.48 09:03:31 INFO - mozfile == 1.2 09:03:31 INFO - mozhttpd == 0.7 09:03:31 INFO - mozinfo == 0.9 09:03:31 INFO - mozleak == 0.1 09:03:31 INFO - mozlog == 3.2 09:03:31 INFO - moznetwork == 0.27 09:03:31 INFO - mozprocess == 0.23 09:03:31 INFO - mozprofile == 0.28 09:03:31 INFO - mozrunner == 6.12 09:03:31 INFO - mozscreenshot == 0.1 09:03:31 INFO - mozsystemmonitor == 0.3 09:03:31 INFO - moztest == 0.7 09:03:31 INFO - mozversion == 1.4 09:03:31 INFO - psutil == 3.1.1 09:03:31 INFO - requests == 1.2.3 09:03:31 INFO - wsgiref == 0.1.2 09:03:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:31 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:31 INFO - 'HOME': '/Users/cltbld', 09:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:31 INFO - 'LOGNAME': 'cltbld', 09:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:31 INFO - 'MOZ_NO_REMOTE': '1', 09:03:31 INFO - 'NO_EM_RESTART': '1', 09:03:31 INFO - 'PAGER': '/bin/cat', 09:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:31 INFO - 'PWD': '/builds/slave/test', 09:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:31 INFO - 'SHELL': '/bin/bash', 09:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:31 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:31 INFO - 'USER': 'cltbld', 09:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:31 INFO - 'XPC_FLAGS': '0x0', 09:03:31 INFO - 'XPC_SERVICE_NAME': '0', 09:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:03:31 INFO - Cleaning up... 09:03:31 INFO - Return code: 0 09:03:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:31 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 0x100a67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100afc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd52cd8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, '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.1OELq6OVrp/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.IkBT0pZvkn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:31 INFO - 'HOME': '/Users/cltbld', 09:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:31 INFO - 'LOGNAME': 'cltbld', 09:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:31 INFO - 'MOZ_NO_REMOTE': '1', 09:03:31 INFO - 'NO_EM_RESTART': '1', 09:03:31 INFO - 'PAGER': '/bin/cat', 09:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:31 INFO - 'PWD': '/builds/slave/test', 09:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:31 INFO - 'SHELL': '/bin/bash', 09:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:03:31 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:31 INFO - 'USER': 'cltbld', 09:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:31 INFO - 'XPC_FLAGS': '0x0', 09:03:31 INFO - 'XPC_SERVICE_NAME': '0', 09:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:32 INFO - Cleaning up... 09:03:32 INFO - Return code: 0 09:03:32 INFO - Running post-action listener: _resource_record_post_action 09:03:32 INFO - Running post-action listener: _start_resource_monitoring 09:03:32 INFO - Starting resource monitoring. 09:03:32 INFO - ##### 09:03:32 INFO - ##### Finished create-virtualenv step (success) 09:03:32 INFO - ##### 09:03:32 INFO - ##### 09:03:32 INFO - ##### Running install step. 09:03:32 INFO - ##### 09:03:32 INFO - Running pre-action listener: _resource_record_pre_action 09:03:32 INFO - Running main action method: install 09:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:32 INFO - Reading from file tmpfile_stdout 09:03:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:32 INFO - Reading from file tmpfile_stdout 09:03:32 INFO - Output received: 09:03:32 INFO - Usage: mozinstall [options] installer 09:03:32 INFO - Options: 09:03:32 INFO - -h, --help show this help message and exit 09:03:32 INFO - -d DEST, --destination=DEST 09:03:32 INFO - Directory to install application into. [default: 09:03:32 INFO - "/builds/slave/test"] 09:03:32 INFO - --app=APP Application being installed. [default: firefox] 09:03:32 INFO - mkdir: /builds/slave/test/build/application 09:03:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:04:06 INFO - Reading from file tmpfile_stdout 09:04:06 INFO - Output received: 09:04:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:04:06 INFO - Running post-action listener: _resource_record_post_action 09:04:06 INFO - ##### 09:04:06 INFO - ##### Finished install step (success) 09:04:06 INFO - ##### 09:04:06 INFO - ##### 09:04:06 INFO - ##### Running run-tests step. 09:04:06 INFO - ##### 09:04:06 INFO - Running pre-action listener: _resource_record_pre_action 09:04:06 INFO - Running main action method: run_tests 09:04:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:06 INFO - 2016-07-13 09:04:06.980 screenresolution[1641:7629] starting screenresolution argv=screenresolution get 09:04:06 INFO - 2016-07-13 09:04:06.983 screenresolution[1641:7629] Display 0: 1600x1200x32@60 09:04:06 INFO - 2016-07-13 09:04:06.988 screenresolution[1642:7631] starting screenresolution argv=screenresolution list 09:04:06 INFO - Available Modes on Display 0 09:04:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:07 INFO - Intel Iris: 09:04:07 INFO - Chipset Model: Intel Iris 09:04:07 INFO - Type: GPU 09:04:07 INFO - Bus: Built-In 09:04:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:07 INFO - Vendor: Intel (0x8086) 09:04:07 INFO - Device ID: 0x0a2e 09:04:07 INFO - Revision ID: 0x0009 09:04:07 INFO - Displays: 09:04:07 INFO - Display: 09:04:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:07 INFO - Display Serial Number: 1600x1200 60 09:04:07 INFO - Main Display: Yes 09:04:07 INFO - Mirror: Off 09:04:07 INFO - Online: Yes 09:04:07 INFO - Rotation: Supported 09:04:07 INFO - Return code: 0 09:04:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:04:07 INFO - Python 2.7.3 09:04:07 INFO - Return code: 0 09:04:07 INFO - grabbing minidump binary from tooltool 09:04:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:04:07 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 0x1009cbd30>, '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 0x100730db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009c3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:04:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:04:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:04:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:04:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:04:07 INFO - Return code: 0 09:04:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:04:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:04:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:04:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:04:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:04:07 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/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:04:07 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/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0337 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:04:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1OELq6OVrp/Render', 09:04:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:04:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:04:07 INFO - 'HOME': '/Users/cltbld', 09:04:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:04:07 INFO - 'LOGNAME': 'cltbld', 09:04:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:04:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:04:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:07 INFO - 'MOZ_NO_REMOTE': '1', 09:04:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:04:07 INFO - 'NO_EM_RESTART': '1', 09:04:07 INFO - 'PAGER': '/bin/cat', 09:04:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:04:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:07 INFO - 'PWD': '/builds/slave/test', 09:04:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:04:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:04:07 INFO - 'SHELL': '/bin/bash', 09:04:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkBT0pZvkn/Listeners', 09:04:07 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 09:04:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:04:07 INFO - 'USER': 'cltbld', 09:04:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:04:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:04:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:07 INFO - 'XPC_FLAGS': '0x0', 09:04:07 INFO - 'XPC_SERVICE_NAME': '0', 09:04:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:04:07 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/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:04:07 INFO - mozversion application_buildid: 20160713080326 09:04:07 INFO - mozversion application_changeset: 5df61b441fa4d208272c35aac1f9975c59b5cff8 09:04:07 INFO - mozversion application_display_name: Nightly 09:04:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:04:07 INFO - mozversion application_name: Firefox 09:04:07 INFO - mozversion application_remotingname: firefox 09:04:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:04:07 INFO - mozversion application_vendor: Mozilla 09:04:07 INFO - mozversion application_version: 50.0a1 09:04:07 INFO - mozversion platform_buildid: 20160713080326 09:04:07 INFO - mozversion platform_changeset: 5df61b441fa4d208272c35aac1f9975c59b5cff8 09:04:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:04:07 INFO - mozversion platform_version: 50.0a1 09:04:07 INFO - using testdate: 1468425847 09:04:07 INFO - actual date: 1468425847 09:04:07 INFO - starting webserver on 'localhost:49271' 09:04:07 INFO - SUITE-START | Running 2 tests 09:04:07 INFO - TEST-START | dromaeo_css 09:04:07 INFO - operating with platform_type : mac_ 09:04:07 INFO - Initialising browser for dromaeo_css test... 09:04:07 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpwDYWXK/profile http://localhost:49271/getInfo.html) 09:04:09 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:04:09 INFO - PROCESS | 1652 | colorDepth:24 09:04:09 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:04:09 INFO - PROCESS | 1652 | __metrics 09:04:10 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:04:10 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:10 INFO - TEST-INFO | 1652: exit 0 09:04:10 INFO - Browser initialized. 09:04:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:04:10 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpwDYWXK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:22 INFO - PROCESS | 1654 | RSS: Main: 243744768 09:04:22 INFO - PROCESS | 1654 | 09:05:14 INFO - PROCESS | 1654 | 1468425914651 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:07:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 09:07:25 INFO - PROCESS | 1654 | RSS: Main: 382201856 09:07:25 INFO - PROCESS | 1654 | 09:10:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 09:10:29 INFO - PROCESS | 1654 | RSS: Main: 377249792 09:10:29 INFO - PROCESS | 1654 | 09:13:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:13:32 INFO - PROCESS | 1654 | RSS: Main: 370069504 09:13:32 INFO - PROCESS | 1654 | 09:16:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 09:16:35 INFO - PROCESS | 1654 | RSS: Main: 369369088 09:16:35 INFO - PROCESS | 1654 | 09:19:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 09:19:38 INFO - PROCESS | 1654 | RSS: Main: 342065152 09:19:38 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 09:22:42 INFO - PROCESS | 1654 | RSS: Main: 380719104 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | __start_tp_report 09:22:42 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:22:42 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:22:42 INFO - PROCESS | 1654 | |i|pagename|runs| 09:22:42 INFO - PROCESS | 1654 | |0;dojo.html;3324.4016077106126;3327.0517902397823;3342.7492938029654;3343.565336506254;3360;5536.501714845663;5611.130274807401;5618.719064046802;5711.343195532515;5727.198192253088;5632.915506267409;5692.203091567184;5699.971500142494;5701.74342154603;5704.714764261791;5077.339945807049;5087.211482220243;5116.948830511703;5133.307003554528;5182.4299327073995;5126.461721519243;5141.897162056756;5159.76781044852;5166.664166829144;5213.895722085575;5165.096108181053;5235.345581802275;5242.685438873661;5246.134387826004;5290.206469029637;925.7780654054005;5114.28400023997;5187.559057480119;5196.350456192976;5211.166213405837;5124.538791508745;5127.1796512558185;5127.307813445212;5132.897342053138;5141.897162056738;5634.746436410331;5668.659880407147;5687.857803554903;5694.914576281359;5741.368449470597;6045.697715114271;6050.5952380952385;6133.44798968095;6143.1092491588915;6150.2927163376335;6217.502599792006;6268.216472940882;6276.46650034743;6279.560430769848;6286.49708023357;5043;5776.364599894008;5811.738471768781;5823.330317013513;5828.562857785683;6176.197094377792;6215.720292586844;6230.034344676583;6266.749330026839;6277.874442511215;6069.079850794667;6072.928835972295;6076.958941343317;6089.543284253698;6095.847603809941;6113.0581742585655;6202;6243.937560624427;6246.656433896089;6268.4671802896355;4920.6555541111775;6009.819705408845;6094.573379863367;6135.208480141525;6165.568410211332;6181.5363847710705;6191.535634827406;6198.535109866778;6209.472194863397;6210.782622608187;4840.878978025577;4848.442429120626;4849.418069831572;4867.196912509467;4927.605791536668;4324;4351.8476853309985;4358.782060896942;4391.14372697321;4395.670324725658;5672.97163514188;5684.317881854204;5685.971570142123;5689.800856969986;5701.344345400249;6138.562405053184;6155.10750941111;6212.689365531705;6226.346233645492;6266.561340706198;5701.173329867146;5708.771649134072;5709.086546152679;5711.828645140652;5732.914006289909;4417.491988421309;5349.117395629755;5381.650192737459;5399.217113518518;5420.349558053059;6183.690815459209;6221.906671399842;6273.874522509615;6291.087792270187;6310.716017779119;6221.502279817694;6231.283402408781;6246.063090536274;6251.749930002749;6255.56211065221;6262.718177682106;6277.968610157102;6305.936940630719;6324.588901721373;6330.905036424365;6002.909956350571;6060.787872424444;6065.332813390612;6130.570860039755;6145.569810113428;6159.7516906329765;6205.906911396242;6219.595726277777;6224.875502489835;6233.844153896189;5888.234529511136;6028.091982597887;6031.396860313934;6041.003234466264;6102.633841969496;5977.133315669291;5989.880202395841;6014.338422773579;6021.337652858269;6039.849003774765;5838.649681018983;5876.882462350814;5895.086261629454;5895.20414744004;5930.9110363343725;5823.155642431739;5898.764049437975;6074.36219196978;6088.169725977901;6098.421246112608;5801.245838041028;5825.50483208932;5826.155207494805;5830.154627579062;5857.502112320501 09:22:42 INFO - PROCESS | 1654 | |1;ext.html;434.5220257716512;541.8945351482975;542.9900385283372;547.3759913698387;579.4321564866428;30205.24486887829;41382.58617413829;42025.57974420255;42208.57791422085;44912;43192;43443;43471.13057738843;43500.69497915068;43510.129797404035;8657.610407531645;9640.325177237579;9829.410235385863;9837.65568205113;9839.852402213972;8650.567471626426;8870.73387798364;8889.288856891466;8899.599518021705;11724.648260552198;11811.822822657667;12156.51373945041;12170.208936419105;12181.269123852553;12232.38838058098;11906.583269585566;12004.819927701092;12009;12022.819657705142;12041.698957526089;8673.82652346956;11477.827832582518;11683.415829208505;11944.70138246542;12028;41432.79283603562;41828.58171418277;41830.79084604588;41842.58157418417;42036.36945445821;3551.964480355215;3577.3560759063357;3721.813909304524;3988.641022308006;4148.191102734968;31756.88850890231;32865.83567082173;33012.834935825405;33050;33519.83240083784;3321.236115693404;3329.9500507492407;3341.114604629775;3393.406153923054;3426.109211604976;528496;530297;530436;531238.3438082824;531250.9999999962;60469.395306047256;60793;61089.694551526954;61136.38863611396;61153;63259.6837015812;63385;63585.68207158935;63644;63675.6816215916;16121.032738035756;16654.333826647042;16656.250468728937;16657.3337066516;16660.41688540895;12978.545750898673;13018.567957524854;13032.934835325763;13034.80447793284;13053.216806991612;12017.519299227934;15007.19913610352;16206.513804585646;16262.268197931122;16571;6134.754609815646;7227.566346019255;7260.785460434513;7341.669624866894;7356.264373562601;883.703959173684;944.7874228298715;955.2739917662626;957.6372315035883;1075.978480430387;29951;30125.343106129374;30130.84934575313;31040.22399440032;31073.378532429226;1068.647346375694;1081.5998080710187;1088.2219213262556;1109.606089838112;1177.4524845946505;15452.072875627273;15929;15970.76043859343;16096.839031609768;16100.516984490485;169095;170746.29253707305;171075;171518.14240928716;171957;59595.40404595899;59703;59832.70083649641;60013.699931500065;60154.39845601488;1257.8930790882878;1346.0644851827885;1383.522684673786;1406.5569345656086;1430.7138572285585;28768.280792980342;31682;31834.6816531841;31840.36319273648;32407.675923240466;49689;49899.501004989484;50414.24378634397;50434.74782626063;50470;538263;538701;539278;539748;540005;35012.64987350094;35300;40435;42498;43725.56274437342;48295;48554.757226213645;49314.75342623264;49480.75259623679;49583.25625115554;38199.42700859545;40047.799761001006;40169.79915100406;40870.79564602158;41037.974050647776;39148;39170;39746.583850931675;39896.40155397613;40641.796791015855;37635.05912352104;38167.61832381641;38283.80858095692;38399.80800095982;38408.615913840506;33709.66290337163;34890.302193956486;35281.647183527835;35703.64296357003;36258;25786.8710656453;26036.479270414107;26073.608895866197;26459.475808450836;26665.733342666324 09:22:42 INFO - PROCESS | 1654 | |2;jquery.html;357.63163941140635;375.40310905660004;387.12123479701086;387.2448724986277;408.62202462721973;15443.227838608082;15675.372985080598;21002.369928902157;21036.318644237275;21050.89474552625;21226.681599775977;21679.34961951144;22033.008514616842;22048.669269960963;22063.44841378966;5271.472852714717;5931.466168044864;6101.694915254242;6136.355682653324;6160.19917410736;4986.501349865002;5964.970175149118;5999.550033747465;6004.789832355868;6019.3980601939675;5697.886042279152;5799.18811366409;5846.678432686222;5877.441643043904;5942.405759424067;5639.71801409932;5799.3620701722775;5879.8530036749;5908.438698323653;5923;5108.131617625013;5136.152534831748;5136.948630513684;5141.434442211354;5143.588512918957;25716.357091072685;26284.342891427677;26317;26525.734742652712;26563.867180664165;1914.8755330903446;2189.37602783206;2569.2292312306236;2604.778593819509;2838.9070207969876;13015.414306356237;15751.720830794111;15870.365185392686;15996.520104396886;16345.83654163455;1884.7172924061501;1897.459224121119;1959.333826498983;1988.8707234029887;2037.765656949455;108488.91511084867;110486;110669.44665276622;112336.43831780789;112426;10543.156547476337;10739.194560408001;11015.228933974706;11029.83455248172;11128.554857805597;13359.064865459519;13471.326433678278;13499.527516537071;13501.189928604315;13513.864861351261;8526.531040792815;8656.307495400353;8715.782105447288;8775.824483510294;8780.824383512294;8789;8822.294216462667;8825.602847871862;8931.598078086501;8980.910190898137;6468.773592924319;6561.704723287464;6574;6581.605103693793;6582.934170658328;1855.0817345414107;1889.6220755848885;1890.1305399516377;1890.1588792987263;1896.3362823012112;798.6565776715952;844.8732690096411;906.2568693671126;934.313279739391;1054.5781687325132;21992.78007219939;22793.31620051401;22821.429464263525;22848.85755712208;22880.427989300068;956.3592393096642;980.01998001998;995.6714284286168;1003.6687892995294;1008.1531513528671;9404.623815047322;11731.05427793822;11843.763124737457;11867.940660296643;12006.699832504257;604201;605802;615203;624340;625711.8714406398;94392.52803735937;118072.40963795126;118229;119810.4009479947;133548.33225833808;998.9150922171697;1008.3647302199568;1018.6485662446444;1019.4698756646354;1032.4063663393579;17989.010604416882;18191.272349106417;19371.806281937;19591.118399671763;19696.31062912791;41206;53887;54004;55579.722101389234;56522.434775651716;57452.71273643772;73739.26260737324;75255;75371.4925701472;76781.61609191919;6584.242812076481;7461.328480436787;8052.83894322122;8064.032316122103;8073.273405393543;13052.54316098932;13095.476180952655;13301.800472993107;13390.598282051555;13656.93171534136;11346.886531134583;11449.198556100993;11474.885251147716;11559.017483513995;11805.704857378634;11237.101031917591;11269.492872820907;11354;11357.602483913353;11488.080953523866;11681.707957300796;11796.115291353011;11983.460744266355;12002.459889304828;12003.759924801281;7068.469864760061;7454.589997550187;7479.813004674927;7971.84056318882;8233.917660823316;8282.585870706562;10580.735481612714;11004.284721493077;11313.830292545783;11817.409129543317 09:22:42 INFO - PROCESS | 1654 | |3;mootools.html;1563.5074951390316;1583.9604009899751;1691.906945118018;1795.3805936951755;1923.6537423263824;7341.853162936738;7641.159472458025;8448.91551084489;8663.826723465527;8878.112188781126;7741.419393545492;7953.284204421615;8410.831783364329;8635;8656.13438656134;2317.6986991691056;2349.6475528670667;2350.059976009593;2350.6591544226076;2351.1065794997876;2320.164740693349;2326.395137264315;2357.834951553392;2359.1742889988454;2360.6223004319313;2067.8759274443505;2139.882306473143;2249.7975182233595;2313.7223533176043;2319.2694301295123;2313.548857972696;2319.5708793873187;2343.355577216279;2343.437574982009;2344.4959333743327;2321.466062805547;2336.205690065386;2337.614293641547;2339.8596084235;2340.0639744102323;8776.78058048554;8845;8858.291336693113;8861.158189971942;8863.601137948743;5161.7161056141895;5276.261323414725;5317.202419637047;5330.999999999961;5339.279197308352;3547.2373439710327;3577.6422357764013;3613.8193090345376;3646.307201631708;3646.726495512847;1073.8067147913373;1081.5241293830768;1082.967510974672;1106.673531308258;1107.512694414463;11541.538338466535;11567.826482602768;11575.958163765217;11590.536378544848;11602.4198790061;4247.277962746341;4520.773961301922;4626.259798432235;4711.010687755541;4742.454617719007;9753.951230243945;9780.315377923476;9783.853242201372;9784.559694813608;9830.820301563715;5300.92048619271;5320.441353657888;5322;5329.786808527693;5343.3587969443915;4712.128256272601;4880.6095512358925;4882.389701287339;4888.266759986031;4909.656324057281;3516.7186625069935;3751.9062023449087;3899.5905429930017;3923.705722070856;3951.8024098794945;1165.4347641393917;1199.91600587958;1261.5962891874512;1268.7906495428335;1285.7878450055823;707.2962402409636;907.7231444409387;942.6794889737522;946.1579194516886;970.45169479244;6033.5474839387225;6080.57435979486;6135.601185922901;6141.692915354304;6169.383061693885;970.8009857979098;978.9265805064649;985.1232403161192;998.0767789794411;1019.1541020952592;3979.522457305142;4051.554329023805;4127.174565086994;4130.987907962508;4134.131832315247;10137.543810528545;10661.520231589597;10663.573457061786;10668.733281667865;10670.199735019905;9750.122488976027;10473.84289235662;10608.681739547827;10612.575496980035;10625.946870265754;7859.6463159157975;8109.432339736162;8160.143184965612;8265.710700125586;8270.586470676562;4909.40444355593;6004.159417681441;6114.327423983447;6116.29662588808;6142.692865356804;9973.202143828621;10020.448875311928;10032.398056116657;10035.849462258218;10051.547680354255;9614.759631009281;9805.411675299505;10080.395176289447;10086.546105425421;10260.281780275309;6475.125858009297;7475.177730449755;7646.464747467624;7655.770326890202;7665.69337226527;8274;8289.875651865348;8358.832823343377;8366.079731229682;8366.288865446506;6167.537434692326;7529.397648188023;7538.849223015619;7542.019537460095;7574.091109066947;7447.143578488543;7464.365528930101;7528.983587215875;7576.166621671501;7590.962045189739;6977.965110174417;6990.370866621825;7060.7881763547175;7071.292870712888;7084.64576771149;5019.7239151847;5043.747812609428;5071.18860982241;5073.188289873602;5180.663256888291;4961.2806143109765;5015.824446144367;5042.899142017212;5171.793128274827;5185.0148471790235 09:22:42 INFO - PROCESS | 1654 | |4;prototype.html;304.9847507624619;311.5638106650689;314.0922733300761;317.47299482858443;322.4566605270119;4677.415323084619;4814.3019262207;5389.407165211824;5579.80034292627;5587.323802859146;4956.727379994099;5145.3311069561;5506.284183056207;5520.751566179521;5524.751386187621;3472.9407530703124;3762.6801721853676;3862.4013277941963;3902.180542086165;3998.9800050999706;4137.193247316774;4189.937150942738;4198.958010419902;4206.537280899099;4208.117439553916;3630.8199835053583;3978.4827972363696;4083.3874918762126;4083.7141400102005;4144.274751918418;3408.7613867029318;3852.576216616198;4072.6378700353725;4133.821860769681;4148.730332528376;2957.52123938031;3553.058439513531;3563.6080031196548;3568.2685049564775;3579.516765236686;4479.440069991251;5336.466353364672;5361.839144825662;5370.758315875795;5413.0769384532305;3906.61696365217;4399.560043995607;4730.810767569293;4736.59738922192;4743.691660042086;4968.7515624219;5696.800611978602;5717.9714101429645;5726.742296596663;5834.941650583482;3416.367971925202;3702.2780557791243;3918.5101862267215;3928.607139286077;3931.3906344516645;5850.3272123705465;6070.332263451017;6087.056506241495;6096.96951515244;6110.572259941806;3452.6374730653424;3863.285292220948;3950.802459876995;3975.8210880510446;3993.5008123984503;3383.6447173046968;3529.347070791912;3782.6217378262504;4059.4925634295714;4065.1259979104634;4719.83480578185;4725.8582242532775;4728.243481043085;4738.502457242009;4760.833370832073;5612.165856242571;5735.254416925773;5757.625754325955;5801.506871915934;5820.214271073435;1133.8525991621036;1354.607163922471;1365.0853927868352;1366.3646404421793;1370.4586688258082;828.2380210206504;841.7432683031611;847.4576271186381;850.974470765878;869.9695510657097;4432.844850430284;5244.2169662315;5375.4624537546715;5447.346318441812;5466.480684334982;4865.708057516489;5103.974480127576;5557.6109672323355;5885.676287804212;5911.438413350811;4500.029980213044;4522.932156017662;5128.487151284841;5344.706041167773;5352.785888564492;2778.8888444461995;3065.095796739967;3082.9537556936666;3098.984505077505;3099.9845000775304;5018.874528136753;6052.004257892371;6063.333033366326;6092.939070609325;6095.542834287447;5223.660462069954;5264.894702105937;5292.735363231823;5656.575756818255;5680.318361796611;4784.378030856036;5306.232574871807;5869.589128761031;6043.879122417615;6066.45401913821;5302.390225124006;5513.401113676989;5711.143328500758;5840.2991641003355;5898.439648233411;5718.8570285741525;5737.540996720336;5763.6541807491685;5766.423357664368;5786.218860453785;5514.283143191359;6033.396660333931;6061.302950160788;6062.878742425039;6071.392860713893;3313.9005829825146;3683.465897444909;3860.517435320585;3973.3642617181113;4001.699872509515;5000.974995125147;5769.423057694197;5926.407359264039;5935.198748168942;5938.851528711817;4765.285207218806;5277.261183434245;5332.9733351333;5757.453041961007;5789.507891829242;4572;5438.6192966493545;5739.62692424991;5763.913541296968;5766.826995190151;5158.607176062517;5418.891622167614;5645.882793952268;5655.660660360391;5657.717114144359;5510.445762483256;5681.829545113653;5780.710964451845;5825.912611310919;5847.532197424111;4727.763611819464;4901.60304313269;5328.653637513549;5336;5689 09:22:42 INFO - PROCESS | 1654 | |5;yui.html;652.1424327009981;706.2090458686272;745.221243800229;747.9102507699074;808.3937047214589;15003;15539.611509712262;16068;16256.268467918957;16282.511524654265;14713.190774507399;14738.410463581471;14882.404703811862;14898.40406383746;15063.62340941477;688.968996395161;712.9536580122301;863.4862256957132;877.0965905117723;895.7984453497943;882.8719835623831;885.7785553611598;891.9866202006975;892.1212605583495;892.5225004622529;938.4697645830119;938.6761567259284;939.5865819039635;940.6284517615537;943.3679434778714;722.1658983873634;909.8316811389914;927.3879239701798;950.676769898231;962.5379817687549;699.4509310191526;700.8142842146835;701.2111374703459;702.1784512120831;705.6189657584898;14964.925175374163;15027.474038408604;15028.17345046033;15028.849711502855;15060.021098628664;13682.726345473135;13801.378937947717;13839.861601383958;13900.513482028182;14071.437142514287;12630.810537841848;12683.175593586388;12735.098578061989;12973.935130324382;13013.60959171217;3710.6861776940073;3855.8264878080554;3881.378979363288;3883.941740873889;3897.1815918657117;114540;116154;116642.41678791637;117854.41072794753;118193;10699.625513107005;10920.672379828618;10978.945105274423;10985.285956412808;11165.553377864795;20634;20676;20700.96495175235;20729.27447539329;20789.792102078787;7866.488678236011;7979.5611241382285;7981.521108733378;8124.350051995826;8230.259276665009;4545.5908968193025;4566.794494247701;4605.6085232755595;4611.815527378934;4780.521947805191;3530.717542596586;3690.686291665185;3691.90770230741;3692.095436618046;3699.9815000924823;571.871328950991;691.6852831961445;700.1772916822713;702.0627462337836;702.199492578461;488.3919520197377;551.9365272993576;552.320645605907;598.5032423088826;612.98467538311;7900.367970562341;8327.6668933242;9337.393069450463;9359.812803744024;9539.666111686058;585.4789237578474;638.8977763557808;655.7967030220551;660.1187414801199;670.2125003121313;4402.8679139625865;4537.591616754508;4568.497465278817;4571.794269257824;4616.84817615178;94049.52975235217;105267.42098868523;153869;154807;159079;118706;120326;121078.68134505197;137343;139435;97504.99999999857;108104;116420;118826;119580.40209798895;9808.411495310018;9846.113849753556;9890.703278901645;9935.20518358544;9938.403695778277;66538.6673066651;67083;68176.6591167041;68253.97619035818;68641.65679171772;86717.56641216754;98106;101527.87776977972;101719;102984;13189.274589897648;13513.391897364445;13767.655808604864;14072.014958953185;14303.64240893986;14465.27673616294;14511.201883896487;14593.416263349347;14615.415383384545;14679.779803302745;13421.798673020108;14198.219097949712;14345.856541434452;14668.853311466748;14791.556253312418;14198.645033873823;14254.714905701621;14457.92771036138;14743;14889.255537223313;13533.323333833467;13596.86403135956;13643.863561364657;14395.640108997359;14554.41782328695;11732;11763.235389699643;11833.290002599872;11984.460699268728;12054.276743395425;11026.889731102587;11236.438178091226;11400.315981041163;11529.769404611692;11608.825867612162 09:22:42 INFO - PROCESS | 1654 | __end_tp_report 09:22:42 INFO - PROCESS | 1654 | __start_cc_report 09:22:42 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1314 09:22:42 INFO - PROCESS | 1654 | __end_cc_report 09:22:42 INFO - PROCESS | 1654 | __startTimestamp1468426962344__endTimestamp 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:22:42 INFO - PROCESS | 1654 | Number of tests: 6 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5660.52 Median:5876.88 stddev:743.51 (12.7%) stddev-sans-first:722.85 09:22:42 INFO - PROCESS | 1654 | Values: 3324.4 3327.1 3342.7 3343.6 3360.0 5536.5 5611.1 5618.7 5711.3 5727.2 5632.9 5692.2 5700.0 5701.7 5704.7 5077.3 5087.2 5116.9 5133.3 5182.4 5126.5 5141.9 5159.8 5166.7 5213.9 5165.1 5235.3 5242.7 5246.1 5290.2 925.8 5114.3 5187.6 5196.4 5211.2 5124.5 5127.2 5127.3 5132.9 5141.9 5634.7 5668.7 5687.9 5694.9 5741.4 6045.7 6050.6 6133.4 6143.1 6150.3 6217.5 6268.2 6276.5 6279.6 6286.5 5043.0 5776.4 5811.7 5823.3 5828.6 6176.2 6215.7 6230.0 6266.7 6277.9 6069.1 6072.9 6077.0 6089.5 6095.8 6113.1 6202.0 6243.9 6246.7 6268.5 4920.7 6009.8 6094.6 6135.2 6165.6 6181.5 6191.5 6198.5 6209.5 6210.8 4840.9 4848.4 4849.4 4867.2 4927.6 4324.0 4351.8 4358.8 4391.1 4395.7 5673.0 5684.3 5686.0 5689.8 5701.3 6138.6 6155.1 6212.7 6226.3 6266.6 5701.2 5708.8 5709.1 5711.8 5732.9 4417.5 5349.1 5381.7 5399.2 5420.3 6183.7 6221.9 6273.9 6291.1 6310.7 6221.5 6231.3 6246.1 6251.7 6255.6 6262.7 6278.0 6305.9 6324.6 6330.9 6002.9 6060.8 6065.3 6130.6 6145.6 6159.8 6205.9 6219.6 6224.9 6233.8 5888.2 6028.1 6031.4 6041.0 6102.6 5977.1 5989.9 6014.3 6021.3 6039.8 5838.6 5876.9 5895.1 5895.2 5930.9 5823.2 5898.8 6074.4 6088.2 6098.4 5801.2 5825.5 5826.2 5830.2 5857.5 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:58215.96 Median:38341.81 stddev:119758.54 (312.3%) stddev-sans-first:120015.91 09:22:42 INFO - PROCESS | 1654 | Values: 434.5 541.9 543.0 547.4 579.4 30205.2 41382.6 42025.6 42208.6 44912.0 43192.0 43443.0 43471.1 43500.7 43510.1 8657.6 9640.3 9829.4 9837.7 9839.9 8650.6 8870.7 8889.3 8899.6 11724.6 11811.8 12156.5 12170.2 12181.3 12232.4 11906.6 12004.8 12009.0 12022.8 12041.7 8673.8 11477.8 11683.4 11944.7 12028.0 41432.8 41828.6 41830.8 41842.6 42036.4 3552.0 3577.4 3721.8 3988.6 4148.2 31756.9 32865.8 33012.8 33050.0 33519.8 3321.2 3330.0 3341.1 3393.4 3426.1 528496.0 530297.0 530436.0 531238.3 531251.0 60469.4 60793.0 61089.7 61136.4 61153.0 63259.7 63385.0 63585.7 63644.0 63675.7 16121.0 16654.3 16656.3 16657.3 16660.4 12978.5 13018.6 13032.9 13034.8 13053.2 12017.5 15007.2 16206.5 16262.3 16571.0 6134.8 7227.6 7260.8 7341.7 7356.3 883.7 944.8 955.3 957.6 1076.0 29951.0 30125.3 30130.8 31040.2 31073.4 1068.6 1081.6 1088.2 1109.6 1177.5 15452.1 15929.0 15970.8 16096.8 16100.5 169095.0 170746.3 171075.0 171518.1 171957.0 59595.4 59703.0 59832.7 60013.7 60154.4 1257.9 1346.1 1383.5 1406.6 1430.7 28768.3 31682.0 31834.7 31840.4 32407.7 49689.0 49899.5 50414.2 50434.7 50470.0 538263.0 538701.0 539278.0 539748.0 540005.0 35012.6 35300.0 40435.0 42498.0 43725.6 48295.0 48554.8 49314.8 49480.8 49583.3 38199.4 40047.8 40169.8 40870.8 41038.0 39148.0 39170.0 39746.6 39896.4 40641.8 37635.1 38167.6 38283.8 38399.8 38408.6 33709.7 34890.3 35281.6 35703.6 36258.0 25786.9 26036.5 26073.6 26459.5 26665.7 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:35070.32 Median:12724.83 stddev:101992.45 (801.5%) stddev-sans-first:102245.26 09:22:42 INFO - PROCESS | 1654 | Values: 357.6 375.4 387.1 387.2 408.6 15443.2 15675.4 21002.4 21036.3 21050.9 21226.7 21679.3 22033.0 22048.7 22063.4 5271.5 5931.5 6101.7 6136.4 6160.2 4986.5 5965.0 5999.6 6004.8 6019.4 5697.9 5799.2 5846.7 5877.4 5942.4 5639.7 5799.4 5879.9 5908.4 5923.0 5108.1 5136.2 5136.9 5141.4 5143.6 25716.4 26284.3 26317.0 26525.7 26563.9 1914.9 2189.4 2569.2 2604.8 2838.9 13015.4 15751.7 15870.4 15996.5 16345.8 1884.7 1897.5 1959.3 1988.9 2037.8 108488.9 110486.0 110669.4 112336.4 112426.0 10543.2 10739.2 11015.2 11029.8 11128.6 13359.1 13471.3 13499.5 13501.2 13513.9 8526.5 8656.3 8715.8 8775.8 8780.8 8789.0 8822.3 8825.6 8931.6 8980.9 6468.8 6561.7 6574.0 6581.6 6582.9 1855.1 1889.6 1890.1 1890.2 1896.3 798.7 844.9 906.3 934.3 1054.6 21992.8 22793.3 22821.4 22848.9 22880.4 956.4 980.0 995.7 1003.7 1008.2 9404.6 11731.1 11843.8 11867.9 12006.7 604201.0 605802.0 615203.0 624340.0 625711.9 94392.5 118072.4 118229.0 119810.4 133548.3 998.9 1008.4 1018.6 1019.5 1032.4 17989.0 18191.3 19371.8 19591.1 19696.3 41206.0 53887.0 54004.0 55579.7 56522.4 57452.7 73739.3 75255.0 75371.5 76781.6 6584.2 7461.3 8052.8 8064.0 8073.3 13052.5 13095.5 13301.8 13390.6 13656.9 11346.9 11449.2 11474.9 11559.0 11805.7 11237.1 11269.5 11354.0 11357.6 11488.1 11681.7 11796.1 11983.5 12002.5 12003.8 7068.5 7454.6 7479.8 7971.8 8233.9 8282.6 10580.7 11004.3 11313.8 11817.4 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5679.84 Median:5031.31 stddev:3149.30 (62.6%) stddev-sans-first:3142.95 09:22:42 INFO - PROCESS | 1654 | Values: 1563.5 1584.0 1691.9 1795.4 1923.7 7341.9 7641.2 8448.9 8663.8 8878.1 7741.4 7953.3 8410.8 8635.0 8656.1 2317.7 2349.6 2350.1 2350.7 2351.1 2320.2 2326.4 2357.8 2359.2 2360.6 2067.9 2139.9 2249.8 2313.7 2319.3 2313.5 2319.6 2343.4 2343.4 2344.5 2321.5 2336.2 2337.6 2339.9 2340.1 8776.8 8845.0 8858.3 8861.2 8863.6 5161.7 5276.3 5317.2 5331.0 5339.3 3547.2 3577.6 3613.8 3646.3 3646.7 1073.8 1081.5 1083.0 1106.7 1107.5 11541.5 11567.8 11576.0 11590.5 11602.4 4247.3 4520.8 4626.3 4711.0 4742.5 9754.0 9780.3 9783.9 9784.6 9830.8 5300.9 5320.4 5322.0 5329.8 5343.4 4712.1 4880.6 4882.4 4888.3 4909.7 3516.7 3751.9 3899.6 3923.7 3951.8 1165.4 1199.9 1261.6 1268.8 1285.8 707.3 907.7 942.7 946.2 970.5 6033.5 6080.6 6135.6 6141.7 6169.4 970.8 978.9 985.1 998.1 1019.2 3979.5 4051.6 4127.2 4131.0 4134.1 10137.5 10661.5 10663.6 10668.7 10670.2 9750.1 10473.8 10608.7 10612.6 10625.9 7859.6 8109.4 8160.1 8265.7 8270.6 4909.4 6004.2 6114.3 6116.3 6142.7 9973.2 10020.4 10032.4 10035.8 10051.5 9614.8 9805.4 10080.4 10086.5 10260.3 6475.1 7475.2 7646.5 7655.8 7665.7 8274.0 8289.9 8358.8 8366.1 8366.3 6167.5 7529.4 7538.8 7542.0 7574.1 7447.1 7464.4 7529.0 7576.2 7591.0 6978.0 6990.4 7060.8 7071.3 7084.6 5019.7 5043.7 5071.2 5073.2 5180.7 4961.3 5015.8 5042.9 5171.8 5185.0 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4589.59 Median:5233.94 stddev:1432.92 (27.4%) stddev-sans-first:1400.39 09:22:42 INFO - PROCESS | 1654 | Values: 305.0 311.6 314.1 317.5 322.5 4677.4 4814.3 5389.4 5579.8 5587.3 4956.7 5145.3 5506.3 5520.8 5524.8 3472.9 3762.7 3862.4 3902.2 3999.0 4137.2 4189.9 4199.0 4206.5 4208.1 3630.8 3978.5 4083.4 4083.7 4144.3 3408.8 3852.6 4072.6 4133.8 4148.7 2957.5 3553.1 3563.6 3568.3 3579.5 4479.4 5336.5 5361.8 5370.8 5413.1 3906.6 4399.6 4730.8 4736.6 4743.7 4968.8 5696.8 5718.0 5726.7 5834.9 3416.4 3702.3 3918.5 3928.6 3931.4 5850.3 6070.3 6087.1 6097.0 6110.6 3452.6 3863.3 3950.8 3975.8 3993.5 3383.6 3529.3 3782.6 4059.5 4065.1 4719.8 4725.9 4728.2 4738.5 4760.8 5612.2 5735.3 5757.6 5801.5 5820.2 1133.9 1354.6 1365.1 1366.4 1370.5 828.2 841.7 847.5 851.0 870.0 4432.8 5244.2 5375.5 5447.3 5466.5 4865.7 5104.0 5557.6 5885.7 5911.4 4500.0 4522.9 5128.5 5344.7 5352.8 2778.9 3065.1 3083.0 3099.0 3100.0 5018.9 6052.0 6063.3 6092.9 6095.5 5223.7 5264.9 5292.7 5656.6 5680.3 4784.4 5306.2 5869.6 6043.9 6066.5 5302.4 5513.4 5711.1 5840.3 5898.4 5718.9 5737.5 5763.7 5766.4 5786.2 5514.3 6033.4 6061.3 6062.9 6071.4 3313.9 3683.5 3860.5 3973.4 4001.7 5001.0 5769.4 5926.4 5935.2 5938.9 4765.3 5277.3 5333.0 5757.5 5789.5 4572.0 5438.6 5739.6 5763.9 5766.8 5158.6 5418.9 5645.9 5655.7 5657.7 5510.4 5681.8 5780.7 5825.9 5847.5 4727.8 4901.6 5328.7 5336.0 5689.0 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:25116.04 Median:12160.25 stddev:39512.44 (324.9%) stddev-sans-first:39580.59 09:22:42 INFO - PROCESS | 1654 | Values: 652.1 706.2 745.2 747.9 808.4 15003.0 15539.6 16068.0 16256.3 16282.5 14713.2 14738.4 14882.4 14898.4 15063.6 689.0 713.0 863.5 877.1 895.8 882.9 885.8 892.0 892.1 892.5 938.5 938.7 939.6 940.6 943.4 722.2 909.8 927.4 950.7 962.5 699.5 700.8 701.2 702.2 705.6 14964.9 15027.5 15028.2 15028.8 15060.0 13682.7 13801.4 13839.9 13900.5 14071.4 12630.8 12683.2 12735.1 12973.9 13013.6 3710.7 3855.8 3881.4 3883.9 3897.2 114540.0 116154.0 116642.4 117854.4 118193.0 10699.6 10920.7 10978.9 10985.3 11165.6 20634.0 20676.0 20701.0 20729.3 20789.8 7866.5 7979.6 7981.5 8124.4 8230.3 4545.6 4566.8 4605.6 4611.8 4780.5 3530.7 3690.7 3691.9 3692.1 3700.0 571.9 691.7 700.2 702.1 702.2 488.4 551.9 552.3 598.5 613.0 7900.4 8327.7 9337.4 9359.8 9539.7 585.5 638.9 655.8 660.1 670.2 4402.9 4537.6 4568.5 4571.8 4616.8 94049.5 105267.4 153869.0 154807.0 159079.0 118706.0 120326.0 121078.7 137343.0 139435.0 97505.0 108104.0 116420.0 118826.0 119580.4 9808.4 9846.1 9890.7 9935.2 9938.4 66538.7 67083.0 68176.7 68254.0 68641.7 86717.6 98106.0 101527.9 101719.0 102984.0 13189.3 13513.4 13767.7 14072.0 14303.6 14465.3 14511.2 14593.4 14615.4 14679.8 13421.8 14198.2 14345.9 14668.9 14791.6 14198.6 14254.7 14457.9 14743.0 14889.3 13533.3 13596.9 13643.9 14395.6 14554.4 11732.0 11763.2 11833.3 11984.5 12054.3 11026.9 11236.4 11400.3 11529.8 11608.8 09:22:42 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:22:42 INFO - PROCESS | 1654 | 09:22:42 INFO - TEST-INFO | 1654: exit 0 09:22:42 INFO - TEST-OK | dromaeo_css | took 1115407ms 09:22:42 INFO - TEST-START | kraken 09:22:42 INFO - operating with platform_type : mac_ 09:22:42 INFO - Initialising browser for kraken test... 09:22:42 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpQJ0HpM/profile http://localhost:49271/getInfo.html) 09:22:45 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:22:45 INFO - PROCESS | 1663 | colorDepth:24 09:22:45 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:22:45 INFO - PROCESS | 1663 | __metrics 09:22:45 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:22:45 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:46 INFO - TEST-INFO | 1663: exit 0 09:22:46 INFO - Browser initialized. 09:22:46 INFO - Running cycle 1/1 for kraken test... 09:22:46 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpQJ0HpM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:58 INFO - PROCESS | 1664 | RSS: Main: 243859456 09:22:58 INFO - PROCESS | 1664 | 09:23:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 09:23:01 INFO - PROCESS | 1664 | RSS: Main: 294330368 09:23:01 INFO - PROCESS | 1664 | 09:23:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 09:23:05 INFO - PROCESS | 1664 | RSS: Main: 304578560 09:23:05 INFO - PROCESS | 1664 | 09:23:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 09:23:09 INFO - PROCESS | 1664 | RSS: Main: 390901760 09:23:09 INFO - PROCESS | 1664 | 09:23:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 09:23:12 INFO - PROCESS | 1664 | RSS: Main: 291217408 09:23:12 INFO - PROCESS | 1664 | 09:23:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:23:14 INFO - PROCESS | 1664 | RSS: Main: 295276544 09:23:14 INFO - PROCESS | 1664 | 09:23:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 09:23:18 INFO - PROCESS | 1664 | RSS: Main: 332165120 09:23:18 INFO - PROCESS | 1664 | 09:23:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 09:23:21 INFO - PROCESS | 1664 | RSS: Main: 335532032 09:23:21 INFO - PROCESS | 1664 | 09:23:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 09:23:25 INFO - PROCESS | 1664 | RSS: Main: 334528512 09:23:25 INFO - PROCESS | 1664 | 09:23:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:27 INFO - PROCESS | 1664 | RSS: Main: 311734272 09:23:27 INFO - PROCESS | 1664 | 09:23:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:29 INFO - PROCESS | 1664 | RSS: Main: 318193664 09:23:29 INFO - PROCESS | 1664 | 09:23:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:32 INFO - PROCESS | 1664 | RSS: Main: 318173184 09:23:32 INFO - PROCESS | 1664 | 09:23:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:36 INFO - PROCESS | 1664 | RSS: Main: 324845568 09:23:36 INFO - PROCESS | 1664 | 09:23:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:39 INFO - PROCESS | 1664 | RSS: Main: 326385664 09:23:39 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 09:23:41 INFO - PROCESS | 1664 | RSS: Main: 369967104 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | __start_tp_report 09:23:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:23:41 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:23:41 INFO - PROCESS | 1664 | |i|pagename|runs| 09:23:41 INFO - PROCESS | 1664 | |0;ai-astar;100;112;116;163;165;114;112;113;113;113 09:23:41 INFO - PROCESS | 1664 | |1;audio-beat-detection;145;113;109;191;138;138;108;128;144;108 09:23:41 INFO - PROCESS | 1664 | |2;audio-dft;184;184;184;185;184;183;184;254;217;184 09:23:41 INFO - PROCESS | 1664 | |3;audio-fft;80;75;58;107;58;60;77;88;139;57 09:23:41 INFO - PROCESS | 1664 | |4;audio-oscillator;85;82;91;70;69;68;96;69;67;97 09:23:41 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;86;86;88;85;87;85;87;85;88;90 09:23:41 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;93;93;95;93;93;94;94;94;93 09:23:41 INFO - PROCESS | 1664 | |7;imaging-desaturate;80;85;84;84;86;86;83;86;85;85 09:23:41 INFO - PROCESS | 1664 | |8;json-parse-financial;56;56;57;55;55;56;54;54;55;55 09:23:41 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;67;77;78;70;74;70;70;70;72 09:23:41 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;83;83;85;85;84;84;84;84;83;84 09:23:41 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;136;134;133;136;142;144;145;148;183;135 09:23:41 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;163;165;162;163;163;165;162;163;162;162 09:23:41 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;65;67;66;65;66;64;66;66;66;65 09:23:41 INFO - PROCESS | 1664 | __end_tp_report 09:23:41 INFO - PROCESS | 1664 | __start_cc_report 09:23:41 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2026 09:23:41 INFO - PROCESS | 1664 | __end_cc_report 09:23:41 INFO - PROCESS | 1664 | __startTimestamp1468427021374__endTimestamp 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:23:41 INFO - PROCESS | 1664 | Number of tests: 14 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:122.10 Median:113.50 stddev:22.50 (19.8%) stddev-sans-first:22.40 09:23:41 INFO - PROCESS | 1664 | Values: 100.0 112.0 116.0 163.0 165.0 114.0 112.0 113.0 113.0 113.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:138.00 stddev:25.65 (18.6%) stddev-sans-first:26.79 09:23:41 INFO - PROCESS | 1664 | Values: 145.0 113.0 109.0 191.0 138.0 138.0 108.0 128.0 144.0 108.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:194.30 Median:184.00 stddev:23.40 (12.7%) stddev-sans-first:24.53 09:23:41 INFO - PROCESS | 1664 | Values: 184.0 184.0 184.0 185.0 184.0 183.0 184.0 254.0 217.0 184.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:79.90 Median:67.50 stddev:26.26 (38.9%) stddev-sans-first:27.85 09:23:41 INFO - PROCESS | 1664 | Values: 80.0 75.0 58.0 107.0 58.0 60.0 77.0 88.0 139.0 57.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:83.50 stddev:12.23 (14.6%) stddev-sans-first:12.80 09:23:41 INFO - PROCESS | 1664 | Values: 85.0 82.0 91.0 70.0 69.0 68.0 96.0 69.0 67.0 97.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 09:23:41 INFO - PROCESS | 1664 | Values: 86.0 86.0 88.0 85.0 87.0 85.0 87.0 85.0 88.0 90.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 09:23:41 INFO - PROCESS | 1664 | Values: 94.0 93.0 93.0 95.0 93.0 93.0 94.0 94.0 94.0 93.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:1.84 (2.2%) stddev-sans-first:1.05 09:23:41 INFO - PROCESS | 1664 | Values: 80.0 85.0 84.0 84.0 86.0 86.0 83.0 86.0 85.0 85.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:0.95 (1.7%) stddev-sans-first:0.97 09:23:41 INFO - PROCESS | 1664 | Values: 56.0 56.0 57.0 55.0 55.0 56.0 54.0 54.0 55.0 55.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.00 stddev:3.92 (5.5%) stddev-sans-first:3.64 09:23:41 INFO - PROCESS | 1664 | Values: 66.0 67.0 77.0 78.0 70.0 74.0 70.0 70.0 70.0 72.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 09:23:41 INFO - PROCESS | 1664 | Values: 83.0 83.0 85.0 85.0 84.0 84.0 84.0 84.0 83.0 84.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:143.00 stddev:14.80 (10.3%) stddev-sans-first:15.44 09:23:41 INFO - PROCESS | 1664 | Values: 136.0 134.0 133.0 136.0 142.0 144.0 145.0 148.0 183.0 135.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.00 Median:163.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 09:23:41 INFO - PROCESS | 1664 | Values: 163.0 165.0 162.0 163.0 163.0 165.0 162.0 163.0 162.0 162.0 09:23:41 INFO - PROCESS | 1664 | 09:23:41 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 09:23:41 INFO - PROCESS | 1664 | Values: 65.0 67.0 66.0 65.0 66.0 64.0 66.0 66.0 66.0 65.0 09:23:41 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:23:41 INFO - PROCESS | 1664 | 09:23:42 INFO - TEST-INFO | 1664: exit 0 09:23:42 INFO - TEST-OK | kraken | took 59390ms 09:23:42 INFO - SUITE-END | took 1174s 09:23:42 INFO - Completed test suite (00:19:34) 09:23:42 INFO - javascript benchmark 09:23:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.4016077106126, 3327.0517902397823, 3342.7492938029654, 3343.565336506254, 3360.0, 5536.501714845663, 5611.130274807401, 5618.719064046802, 5711.343195532515, 5727.198192253088, 5632.915506267409, 5692.203091567184, 5699.971500142494, 5701.74342154603, 5704.714764261791, 5077.339945807049, 5087.211482220243, 5116.948830511703, 5133.307003554528, 5182.4299327073995, 5126.461721519243, 5141.897162056756, 5159.76781044852, 5166.664166829144, 5213.895722085575, 5165.096108181053, 5235.345581802275, 5242.685438873661, 5246.134387826004, 5290.206469029637, 925.7780654054005, 5114.28400023997, 5187.559057480119, 5196.350456192976, 5211.166213405837, 5124.538791508745, 5127.1796512558185, 5127.307813445212, 5132.897342053138, 5141.897162056738, 5634.746436410331, 5668.659880407147, 5687.857803554903, 5694.914576281359, 5741.368449470597, 6045.697715114271, 6050.5952380952385, 6133.44798968095, 6143.1092491588915, 6150.2927163376335, 6217.502599792006, 6268.216472940882, 6276.46650034743, 6279.560430769848, 6286.49708023357, 5043.0, 5776.364599894008, 5811.738471768781, 5823.330317013513, 5828.562857785683, 6176.197094377792, 6215.720292586844, 6230.034344676583, 6266.749330026839, 6277.874442511215, 6069.079850794667, 6072.928835972295, 6076.958941343317, 6089.543284253698, 6095.847603809941, 6113.0581742585655, 6202.0, 6243.937560624427, 6246.656433896089, 6268.4671802896355, 4920.6555541111775, 6009.819705408845, 6094.573379863367, 6135.208480141525, 6165.568410211332, 6181.5363847710705, 6191.535634827406, 6198.535109866778, 6209.472194863397, 6210.782622608187, 4840.878978025577, 4848.442429120626, 4849.418069831572, 4867.196912509467, 4927.605791536668, 4324.0, 4351.8476853309985, 4358.782060896942, 4391.14372697321, 4395.670324725658, 5672.97163514188, 5684.317881854204, 5685.971570142123, 5689.800856969986, 5701.344345400249, 6138.562405053184, 6155.10750941111, 6212.689365531705, 6226.346233645492, 6266.561340706198, 5701.173329867146, 5708.771649134072, 5709.086546152679, 5711.828645140652, 5732.914006289909, 4417.491988421309, 5349.117395629755, 5381.650192737459, 5399.217113518518, 5420.349558053059, 6183.690815459209, 6221.906671399842, 6273.874522509615, 6291.087792270187, 6310.716017779119, 6221.502279817694, 6231.283402408781, 6246.063090536274, 6251.749930002749, 6255.56211065221, 6262.718177682106, 6277.968610157102, 6305.936940630719, 6324.588901721373, 6330.905036424365, 6002.909956350571, 6060.787872424444, 6065.332813390612, 6130.570860039755, 6145.569810113428, 6159.7516906329765, 6205.906911396242, 6219.595726277777, 6224.875502489835, 6233.844153896189, 5888.234529511136, 6028.091982597887, 6031.396860313934, 6041.003234466264, 6102.633841969496, 5977.133315669291, 5989.880202395841, 6014.338422773579, 6021.337652858269, 6039.849003774765, 5838.649681018983, 5876.882462350814, 5895.086261629454, 5895.20414744004, 5930.9110363343725, 5823.155642431739, 5898.764049437975, 6074.36219196978, 6088.169725977901, 6098.421246112608, 5801.245838041028, 5825.50483208932, 5826.155207494805, 5830.154627579062, 5857.502112320501], "value": 5614.061376348901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.5220257716512, 541.8945351482975, 542.9900385283372, 547.3759913698387, 579.4321564866428, 30205.24486887829, 41382.58617413829, 42025.57974420255, 42208.57791422085, 44912.0, 43192.0, 43443.0, 43471.13057738843, 43500.69497915068, 43510.129797404035, 8657.610407531645, 9640.325177237579, 9829.410235385863, 9837.65568205113, 9839.852402213972, 8650.567471626426, 8870.73387798364, 8889.288856891466, 8899.599518021705, 11724.648260552198, 11811.822822657667, 12156.51373945041, 12170.208936419105, 12181.269123852553, 12232.38838058098, 11906.583269585566, 12004.819927701092, 12009.0, 12022.819657705142, 12041.698957526089, 8673.82652346956, 11477.827832582518, 11683.415829208505, 11944.70138246542, 12028.0, 41432.79283603562, 41828.58171418277, 41830.79084604588, 41842.58157418417, 42036.36945445821, 3551.964480355215, 3577.3560759063357, 3721.813909304524, 3988.641022308006, 4148.191102734968, 31756.88850890231, 32865.83567082173, 33012.834935825405, 33050.0, 33519.83240083784, 3321.236115693404, 3329.9500507492407, 3341.114604629775, 3393.406153923054, 3426.109211604976, 528496.0, 530297.0, 530436.0, 531238.3438082824, 531250.9999999962, 60469.395306047256, 60793.0, 61089.694551526954, 61136.38863611396, 61153.0, 63259.6837015812, 63385.0, 63585.68207158935, 63644.0, 63675.6816215916, 16121.032738035756, 16654.333826647042, 16656.250468728937, 16657.3337066516, 16660.41688540895, 12978.545750898673, 13018.567957524854, 13032.934835325763, 13034.80447793284, 13053.216806991612, 12017.519299227934, 15007.19913610352, 16206.513804585646, 16262.268197931122, 16571.0, 6134.754609815646, 7227.566346019255, 7260.785460434513, 7341.669624866894, 7356.264373562601, 883.703959173684, 944.7874228298715, 955.2739917662626, 957.6372315035883, 1075.978480430387, 29951.0, 30125.343106129374, 30130.84934575313, 31040.22399440032, 31073.378532429226, 1068.647346375694, 1081.5998080710187, 1088.2219213262556, 1109.606089838112, 1177.4524845946505, 15452.072875627273, 15929.0, 15970.76043859343, 16096.839031609768, 16100.516984490485, 169095.0, 170746.29253707305, 171075.0, 171518.14240928716, 171957.0, 59595.40404595899, 59703.0, 59832.70083649641, 60013.699931500065, 60154.39845601488, 1257.8930790882878, 1346.0644851827885, 1383.522684673786, 1406.5569345656086, 1430.7138572285585, 28768.280792980342, 31682.0, 31834.6816531841, 31840.36319273648, 32407.675923240466, 49689.0, 49899.501004989484, 50414.24378634397, 50434.74782626063, 50470.0, 538263.0, 538701.0, 539278.0, 539748.0, 540005.0, 35012.64987350094, 35300.0, 40435.0, 42498.0, 43725.56274437342, 48295.0, 48554.757226213645, 49314.75342623264, 49480.75259623679, 49583.25625115554, 38199.42700859545, 40047.799761001006, 40169.79915100406, 40870.79564602158, 41037.974050647776, 39148.0, 39170.0, 39746.583850931675, 39896.40155397613, 40641.796791015855, 37635.05912352104, 38167.61832381641, 38283.80858095692, 38399.80800095982, 38408.615913840506, 33709.66290337163, 34890.302193956486, 35281.647183527835, 35703.64296357003, 36258.0, 25786.8710656453, 26036.479270414107, 26073.608895866197, 26459.475808450836, 26665.733342666324], "value": 20043.94610235801, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.63163941140635, 375.40310905660004, 387.12123479701086, 387.2448724986277, 408.62202462721973, 15443.227838608082, 15675.372985080598, 21002.369928902157, 21036.318644237275, 21050.89474552625, 21226.681599775977, 21679.34961951144, 22033.008514616842, 22048.669269960963, 22063.44841378966, 5271.472852714717, 5931.466168044864, 6101.694915254242, 6136.355682653324, 6160.19917410736, 4986.501349865002, 5964.970175149118, 5999.550033747465, 6004.789832355868, 6019.3980601939675, 5697.886042279152, 5799.18811366409, 5846.678432686222, 5877.441643043904, 5942.405759424067, 5639.71801409932, 5799.3620701722775, 5879.8530036749, 5908.438698323653, 5923.0, 5108.131617625013, 5136.152534831748, 5136.948630513684, 5141.434442211354, 5143.588512918957, 25716.357091072685, 26284.342891427677, 26317.0, 26525.734742652712, 26563.867180664165, 1914.8755330903446, 2189.37602783206, 2569.2292312306236, 2604.778593819509, 2838.9070207969876, 13015.414306356237, 15751.720830794111, 15870.365185392686, 15996.520104396886, 16345.83654163455, 1884.7172924061501, 1897.459224121119, 1959.333826498983, 1988.8707234029887, 2037.765656949455, 108488.91511084867, 110486.0, 110669.44665276622, 112336.43831780789, 112426.0, 10543.156547476337, 10739.194560408001, 11015.228933974706, 11029.83455248172, 11128.554857805597, 13359.064865459519, 13471.326433678278, 13499.527516537071, 13501.189928604315, 13513.864861351261, 8526.531040792815, 8656.307495400353, 8715.782105447288, 8775.824483510294, 8780.824383512294, 8789.0, 8822.294216462667, 8825.602847871862, 8931.598078086501, 8980.910190898137, 6468.773592924319, 6561.704723287464, 6574.0, 6581.605103693793, 6582.934170658328, 1855.0817345414107, 1889.6220755848885, 1890.1305399516377, 1890.1588792987263, 1896.3362823012112, 798.6565776715952, 844.8732690096411, 906.2568693671126, 934.313279739391, 1054.5781687325132, 21992.78007219939, 22793.31620051401, 22821.429464263525, 22848.85755712208, 22880.427989300068, 956.3592393096642, 980.01998001998, 995.6714284286168, 1003.6687892995294, 1008.1531513528671, 9404.623815047322, 11731.05427793822, 11843.763124737457, 11867.940660296643, 12006.699832504257, 604201.0, 605802.0, 615203.0, 624340.0, 625711.8714406398, 94392.52803735937, 118072.40963795126, 118229.0, 119810.4009479947, 133548.33225833808, 998.9150922171697, 1008.3647302199568, 1018.6485662446444, 1019.4698756646354, 1032.4063663393579, 17989.010604416882, 18191.272349106417, 19371.806281937, 19591.118399671763, 19696.31062912791, 41206.0, 53887.0, 54004.0, 55579.722101389234, 56522.434775651716, 57452.71273643772, 73739.26260737324, 75255.0, 75371.4925701472, 76781.61609191919, 6584.242812076481, 7461.328480436787, 8052.83894322122, 8064.032316122103, 8073.273405393543, 13052.54316098932, 13095.476180952655, 13301.800472993107, 13390.598282051555, 13656.93171534136, 11346.886531134583, 11449.198556100993, 11474.885251147716, 11559.017483513995, 11805.704857378634, 11237.101031917591, 11269.492872820907, 11354.0, 11357.602483913353, 11488.080953523866, 11681.707957300796, 11796.115291353011, 11983.460744266355, 12002.459889304828, 12003.759924801281, 7068.469864760061, 7454.589997550187, 7479.813004674927, 7971.84056318882, 8233.917660823316, 8282.585870706562, 10580.735481612714, 11004.284721493077, 11313.830292545783, 11817.409129543317], "value": 9769.815842921467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1563.5074951390316, 1583.9604009899751, 1691.906945118018, 1795.3805936951755, 1923.6537423263824, 7341.853162936738, 7641.159472458025, 8448.91551084489, 8663.826723465527, 8878.112188781126, 7741.419393545492, 7953.284204421615, 8410.831783364329, 8635.0, 8656.13438656134, 2317.6986991691056, 2349.6475528670667, 2350.059976009593, 2350.6591544226076, 2351.1065794997876, 2320.164740693349, 2326.395137264315, 2357.834951553392, 2359.1742889988454, 2360.6223004319313, 2067.8759274443505, 2139.882306473143, 2249.7975182233595, 2313.7223533176043, 2319.2694301295123, 2313.548857972696, 2319.5708793873187, 2343.355577216279, 2343.437574982009, 2344.4959333743327, 2321.466062805547, 2336.205690065386, 2337.614293641547, 2339.8596084235, 2340.0639744102323, 8776.78058048554, 8845.0, 8858.291336693113, 8861.158189971942, 8863.601137948743, 5161.7161056141895, 5276.261323414725, 5317.202419637047, 5330.999999999961, 5339.279197308352, 3547.2373439710327, 3577.6422357764013, 3613.8193090345376, 3646.307201631708, 3646.726495512847, 1073.8067147913373, 1081.5241293830768, 1082.967510974672, 1106.673531308258, 1107.512694414463, 11541.538338466535, 11567.826482602768, 11575.958163765217, 11590.536378544848, 11602.4198790061, 4247.277962746341, 4520.773961301922, 4626.259798432235, 4711.010687755541, 4742.454617719007, 9753.951230243945, 9780.315377923476, 9783.853242201372, 9784.559694813608, 9830.820301563715, 5300.92048619271, 5320.441353657888, 5322.0, 5329.786808527693, 5343.3587969443915, 4712.128256272601, 4880.6095512358925, 4882.389701287339, 4888.266759986031, 4909.656324057281, 3516.7186625069935, 3751.9062023449087, 3899.5905429930017, 3923.705722070856, 3951.8024098794945, 1165.4347641393917, 1199.91600587958, 1261.5962891874512, 1268.7906495428335, 1285.7878450055823, 707.2962402409636, 907.7231444409387, 942.6794889737522, 946.1579194516886, 970.45169479244, 6033.5474839387225, 6080.57435979486, 6135.601185922901, 6141.692915354304, 6169.383061693885, 970.8009857979098, 978.9265805064649, 985.1232403161192, 998.0767789794411, 1019.1541020952592, 3979.522457305142, 4051.554329023805, 4127.174565086994, 4130.987907962508, 4134.131832315247, 10137.543810528545, 10661.520231589597, 10663.573457061786, 10668.733281667865, 10670.199735019905, 9750.122488976027, 10473.84289235662, 10608.681739547827, 10612.575496980035, 10625.946870265754, 7859.6463159157975, 8109.432339736162, 8160.143184965612, 8265.710700125586, 8270.586470676562, 4909.40444355593, 6004.159417681441, 6114.327423983447, 6116.29662588808, 6142.692865356804, 9973.202143828621, 10020.448875311928, 10032.398056116657, 10035.849462258218, 10051.547680354255, 9614.759631009281, 9805.411675299505, 10080.395176289447, 10086.546105425421, 10260.281780275309, 6475.125858009297, 7475.177730449755, 7646.464747467624, 7655.770326890202, 7665.69337226527, 8274.0, 8289.875651865348, 8358.832823343377, 8366.079731229682, 8366.288865446506, 6167.537434692326, 7529.397648188023, 7538.849223015619, 7542.019537460095, 7574.091109066947, 7447.143578488543, 7464.365528930101, 7528.983587215875, 7576.166621671501, 7590.962045189739, 6977.965110174417, 6990.370866621825, 7060.7881763547175, 7071.292870712888, 7084.64576771149, 5019.7239151847, 5043.747812609428, 5071.18860982241, 5073.188289873602, 5180.663256888291, 4961.2806143109765, 5015.824446144367, 5042.899142017212, 5171.793128274827, 5185.0148471790235], "value": 4582.918636005927, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.9847507624619, 311.5638106650689, 314.0922733300761, 317.47299482858443, 322.4566605270119, 4677.415323084619, 4814.3019262207, 5389.407165211824, 5579.80034292627, 5587.323802859146, 4956.727379994099, 5145.3311069561, 5506.284183056207, 5520.751566179521, 5524.751386187621, 3472.9407530703124, 3762.6801721853676, 3862.4013277941963, 3902.180542086165, 3998.9800050999706, 4137.193247316774, 4189.937150942738, 4198.958010419902, 4206.537280899099, 4208.117439553916, 3630.8199835053583, 3978.4827972363696, 4083.3874918762126, 4083.7141400102005, 4144.274751918418, 3408.7613867029318, 3852.576216616198, 4072.6378700353725, 4133.821860769681, 4148.730332528376, 2957.52123938031, 3553.058439513531, 3563.6080031196548, 3568.2685049564775, 3579.516765236686, 4479.440069991251, 5336.466353364672, 5361.839144825662, 5370.758315875795, 5413.0769384532305, 3906.61696365217, 4399.560043995607, 4730.810767569293, 4736.59738922192, 4743.691660042086, 4968.7515624219, 5696.800611978602, 5717.9714101429645, 5726.742296596663, 5834.941650583482, 3416.367971925202, 3702.2780557791243, 3918.5101862267215, 3928.607139286077, 3931.3906344516645, 5850.3272123705465, 6070.332263451017, 6087.056506241495, 6096.96951515244, 6110.572259941806, 3452.6374730653424, 3863.285292220948, 3950.802459876995, 3975.8210880510446, 3993.5008123984503, 3383.6447173046968, 3529.347070791912, 3782.6217378262504, 4059.4925634295714, 4065.1259979104634, 4719.83480578185, 4725.8582242532775, 4728.243481043085, 4738.502457242009, 4760.833370832073, 5612.165856242571, 5735.254416925773, 5757.625754325955, 5801.506871915934, 5820.214271073435, 1133.8525991621036, 1354.607163922471, 1365.0853927868352, 1366.3646404421793, 1370.4586688258082, 828.2380210206504, 841.7432683031611, 847.4576271186381, 850.974470765878, 869.9695510657097, 4432.844850430284, 5244.2169662315, 5375.4624537546715, 5447.346318441812, 5466.480684334982, 4865.708057516489, 5103.974480127576, 5557.6109672323355, 5885.676287804212, 5911.438413350811, 4500.029980213044, 4522.932156017662, 5128.487151284841, 5344.706041167773, 5352.785888564492, 2778.8888444461995, 3065.095796739967, 3082.9537556936666, 3098.984505077505, 3099.9845000775304, 5018.874528136753, 6052.004257892371, 6063.333033366326, 6092.939070609325, 6095.542834287447, 5223.660462069954, 5264.894702105937, 5292.735363231823, 5656.575756818255, 5680.318361796611, 4784.378030856036, 5306.232574871807, 5869.589128761031, 6043.879122417615, 6066.45401913821, 5302.390225124006, 5513.401113676989, 5711.143328500758, 5840.2991641003355, 5898.439648233411, 5718.8570285741525, 5737.540996720336, 5763.6541807491685, 5766.423357664368, 5786.218860453785, 5514.283143191359, 6033.396660333931, 6061.302950160788, 6062.878742425039, 6071.392860713893, 3313.9005829825146, 3683.465897444909, 3860.517435320585, 3973.3642617181113, 4001.699872509515, 5000.974995125147, 5769.423057694197, 5926.407359264039, 5935.198748168942, 5938.851528711817, 4765.285207218806, 5277.261183434245, 5332.9733351333, 5757.453041961007, 5789.507891829242, 4572.0, 5438.6192966493545, 5739.62692424991, 5763.913541296968, 5766.826995190151, 5158.607176062517, 5418.891622167614, 5645.882793952268, 5655.660660360391, 5657.717114144359, 5510.445762483256, 5681.829545113653, 5780.710964451845, 5825.912611310919, 5847.532197424111, 4727.763611819464, 4901.60304313269, 5328.653637513549, 5336.0, 5689.0], "value": 4130.9652330896515, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.1424327009981, 706.2090458686272, 745.221243800229, 747.9102507699074, 808.3937047214589, 15003.0, 15539.611509712262, 16068.0, 16256.268467918957, 16282.511524654265, 14713.190774507399, 14738.410463581471, 14882.404703811862, 14898.40406383746, 15063.62340941477, 688.968996395161, 712.9536580122301, 863.4862256957132, 877.0965905117723, 895.7984453497943, 882.8719835623831, 885.7785553611598, 891.9866202006975, 892.1212605583495, 892.5225004622529, 938.4697645830119, 938.6761567259284, 939.5865819039635, 940.6284517615537, 943.3679434778714, 722.1658983873634, 909.8316811389914, 927.3879239701798, 950.676769898231, 962.5379817687549, 699.4509310191526, 700.8142842146835, 701.2111374703459, 702.1784512120831, 705.6189657584898, 14964.925175374163, 15027.474038408604, 15028.17345046033, 15028.849711502855, 15060.021098628664, 13682.726345473135, 13801.378937947717, 13839.861601383958, 13900.513482028182, 14071.437142514287, 12630.810537841848, 12683.175593586388, 12735.098578061989, 12973.935130324382, 13013.60959171217, 3710.6861776940073, 3855.8264878080554, 3881.378979363288, 3883.941740873889, 3897.1815918657117, 114540.0, 116154.0, 116642.41678791637, 117854.41072794753, 118193.0, 10699.625513107005, 10920.672379828618, 10978.945105274423, 10985.285956412808, 11165.553377864795, 20634.0, 20676.0, 20700.96495175235, 20729.27447539329, 20789.792102078787, 7866.488678236011, 7979.5611241382285, 7981.521108733378, 8124.350051995826, 8230.259276665009, 4545.5908968193025, 4566.794494247701, 4605.6085232755595, 4611.815527378934, 4780.521947805191, 3530.717542596586, 3690.686291665185, 3691.90770230741, 3692.095436618046, 3699.9815000924823, 571.871328950991, 691.6852831961445, 700.1772916822713, 702.0627462337836, 702.199492578461, 488.3919520197377, 551.9365272993576, 552.320645605907, 598.5032423088826, 612.98467538311, 7900.367970562341, 8327.6668933242, 9337.393069450463, 9359.812803744024, 9539.666111686058, 585.4789237578474, 638.8977763557808, 655.7967030220551, 660.1187414801199, 670.2125003121313, 4402.8679139625865, 4537.591616754508, 4568.497465278817, 4571.794269257824, 4616.84817615178, 94049.52975235217, 105267.42098868523, 153869.0, 154807.0, 159079.0, 118706.0, 120326.0, 121078.68134505197, 137343.0, 139435.0, 97504.99999999857, 108104.0, 116420.0, 118826.0, 119580.40209798895, 9808.411495310018, 9846.113849753556, 9890.703278901645, 9935.20518358544, 9938.403695778277, 66538.6673066651, 67083.0, 68176.6591167041, 68253.97619035818, 68641.65679171772, 86717.56641216754, 98106.0, 101527.87776977972, 101719.0, 102984.0, 13189.274589897648, 13513.391897364445, 13767.655808604864, 14072.014958953185, 14303.64240893986, 14465.27673616294, 14511.201883896487, 14593.416263349347, 14615.415383384545, 14679.779803302745, 13421.798673020108, 14198.219097949712, 14345.856541434452, 14668.853311466748, 14791.556253312418, 14198.645033873823, 14254.714905701621, 14457.92771036138, 14743.0, 14889.255537223313, 13533.323333833467, 13596.86403135956, 13643.863561364657, 14395.640108997359, 14554.41782328695, 11732.0, 11763.235389699643, 11833.290002599872, 11984.460699268728, 12054.276743395425, 11026.889731102587, 11236.438178091226, 11400.315981041163, 11529.769404611692, 11608.825867612162], "value": 7951.638633880355, "unit": "score"}], "name": "dromaeo_css", "value": 7409.81273863995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 116.0, 163.0, 165.0, 114.0, 112.0, 113.0, 113.0, 113.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 109.0, 191.0, 138.0, 138.0, 108.0, 128.0, 144.0, 108.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 185.0, 184.0, 183.0, 184.0, 254.0, 217.0, 184.0], "value": 194.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 107.0, 58.0, 60.0, 77.0, 88.0, 139.0, 57.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 91.0, 70.0, 69.0, 68.0, 96.0, 69.0, 67.0, 97.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 88.0, 85.0, 87.0, 85.0, 87.0, 85.0, 88.0, 90.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 84.0, 84.0, 86.0, 86.0, 83.0, 86.0, 85.0, 85.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 55.0, 55.0, 56.0, 54.0, 54.0, 55.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 77.0, 78.0, 70.0, 74.0, 70.0, 70.0, 70.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 85.0, 84.0, 84.0, 84.0, 84.0, 83.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 133.0, 136.0, 142.0, 144.0, 145.0, 148.0, 183.0, 135.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 163.0, 163.0, 165.0, 162.0, 163.0, 162.0, 162.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 65.0, 66.0, 64.0, 66.0, 66.0, 66.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1455.3999999999999, "alertThreshold": 2.0}]} 09:23:42 INFO - Return code: 0 09:23:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:42 INFO - # TBPL SUCCESS # 09:23:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:42 INFO - 2016-07-13 09:23:42.708 screenresolution[1668:9775] starting screenresolution argv=screenresolution get 09:23:42 INFO - 2016-07-13 09:23:42.710 screenresolution[1668:9775] Display 0: 1600x1200x32@60 09:23:42 INFO - 2016-07-13 09:23:42.715 screenresolution[1669:9778] starting screenresolution argv=screenresolution list 09:23:42 INFO - Available Modes on Display 0 09:23:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:42 INFO - Intel Iris: 09:23:42 INFO - Chipset Model: Intel Iris 09:23:42 INFO - Type: GPU 09:23:42 INFO - Bus: Built-In 09:23:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:42 INFO - Vendor: Intel (0x8086) 09:23:42 INFO - Device ID: 0x0a2e 09:23:42 INFO - Revision ID: 0x0009 09:23:42 INFO - Displays: 09:23:42 INFO - Display: 09:23:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:42 INFO - Display Serial Number: 1600x1200 60 09:23:42 INFO - Main Display: Yes 09:23:42 INFO - Mirror: Off 09:23:42 INFO - Online: Yes 09:23:42 INFO - Rotation: Supported 09:23:42 INFO - Return code: 0 09:23:42 INFO - Running post-action listener: _resource_record_post_action 09:23:42 INFO - ##### 09:23:42 INFO - ##### Finished run-tests step (success) 09:23:42 INFO - ##### 09:23:42 INFO - Running post-run listener: _resource_record_post_run 09:23:43 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 36869632; Write bytes: 704853504; Read time: 239; Write time: 1993 09:23:43 INFO - TinderboxPrint: CPU usage
53.3% 09:23:43 INFO - TinderboxPrint: I/O read bytes / time
36,869,632 / 239 09:23:43 INFO - TinderboxPrint: I/O write bytes / time
704,853,504 / 1,993 09:23:43 INFO - TinderboxPrint: CPU idle
2,261.8 (46.7%) 09:23:43 INFO - TinderboxPrint: CPU system
962.1 (19.9%) 09:23:43 INFO - TinderboxPrint: CPU user
1,617.7 (33.4%) 09:23:43 INFO - TinderboxPrint: Swap in / out
443,523,072 / 0 09:23:43 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394418688; Write bytes: 389420544; Read time: 29347; Write time: 872 09:23:43 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 35915264; Write bytes: 294440960; Read time: 227; Write time: 1070 09:23:43 INFO - Running post-run listener: _upload_blobber_files 09:23:43 INFO - Blob upload gear active. 09:23:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:43 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'] 09:23:43 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 09:23:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:23:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:44 INFO - (blobuploader) - INFO - Done attempting. 09:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:45 INFO - (blobuploader) - INFO - Done attempting. 09:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:48 INFO - (blobuploader) - INFO - Done attempting. 09:23:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:48 INFO - Return code: 0 09:23:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15e8d281ac7d1f4a0a059641c49f4565de995762d1762c7bc420d0d9d7a66a5af6e3fd7c6561ec87fd007cf8e784fca1c6480d701c1e0708b0049afd50f1940b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26d6ca99bce8f825a9164c10d069aa65be5725577901e2bb0c6f81592e2fe2e8af00d03abed3695296d6dd5ad6f08ee41a0e5ebf1152b5d032a42997c5883547", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cb87429eeac8766d26b614e35b0f06a088f97374170161cbd2b0d71e2606fdce8d055e838a598af561f4524ef7721b3c41dd01e67d0c53287c939dc3d5139d6"} 09:23:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:48 INFO - Contents: 09:23:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15e8d281ac7d1f4a0a059641c49f4565de995762d1762c7bc420d0d9d7a66a5af6e3fd7c6561ec87fd007cf8e784fca1c6480d701c1e0708b0049afd50f1940b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26d6ca99bce8f825a9164c10d069aa65be5725577901e2bb0c6f81592e2fe2e8af00d03abed3695296d6dd5ad6f08ee41a0e5ebf1152b5d032a42997c5883547", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cb87429eeac8766d26b614e35b0f06a088f97374170161cbd2b0d71e2606fdce8d055e838a598af561f4524ef7721b3c41dd01e67d0c53287c939dc3d5139d6"} 09:23:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:48 INFO - Copying logs to upload dir... 09:23:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.901465 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-13 09:23:48.581460) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 09:23:48.585569) ========= 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.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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/15e8d281ac7d1f4a0a059641c49f4565de995762d1762c7bc420d0d9d7a66a5af6e3fd7c6561ec87fd007cf8e784fca1c6480d701c1e0708b0049afd50f1940b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26d6ca99bce8f825a9164c10d069aa65be5725577901e2bb0c6f81592e2fe2e8af00d03abed3695296d6dd5ad6f08ee41a0e5ebf1152b5d032a42997c5883547", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cb87429eeac8766d26b614e35b0f06a088f97374170161cbd2b0d71e2606fdce8d055e838a598af561f4524ef7721b3c41dd01e67d0c53287c939dc3d5139d6"} build_url:https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010948 build_url: 'https://queue.taskcluster.net/v1/task/f41s37OEQeG_GJoM62ZCjw/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/15e8d281ac7d1f4a0a059641c49f4565de995762d1762c7bc420d0d9d7a66a5af6e3fd7c6561ec87fd007cf8e784fca1c6480d701c1e0708b0049afd50f1940b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/26d6ca99bce8f825a9164c10d069aa65be5725577901e2bb0c6f81592e2fe2e8af00d03abed3695296d6dd5ad6f08ee41a0e5ebf1152b5d032a42997c5883547", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4cb87429eeac8766d26b614e35b0f06a088f97374170161cbd2b0d71e2606fdce8d055e838a598af561f4524ef7721b3c41dd01e67d0c53287c939dc3d5139d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 09:23:48.619770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:23:48.620216) ========= 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.1OELq6OVrp/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.IkBT0pZvkn/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.005009 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:23:48.669121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 09:23:48.669438) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 09:23:48.669797) ========= ========= Total master_lag: 0.17 =========