builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0121 starttime: 1467217732.34 results: success (0) buildid: 20160629071428 builduid: 3ce221c54d684cfe9ee4af296fec3836 revision: b69a5bbb5e40bd426e35222baa600b481e50d265 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.341161) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.341745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.342066) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.368752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.369139) ========= 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.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-06-29 09:28:52-- 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% 16.4M=0.001s 2016-06-29 09:28:52 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129724 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.523305) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.523660) ========= 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.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.028199 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:52.573464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:28:52.573875) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b69a5bbb5e40bd426e35222baa600b481e50d265 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b69a5bbb5e40bd426e35222baa600b481e50d265 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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-06-29 09:28:52,649 truncating revision to first 12 chars 2016-06-29 09:28:52,649 Setting DEBUG logging. 2016-06-29 09:28:52,649 attempt 1/10 2016-06-29 09:28:52,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b69a5bbb5e40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 09:28:53,649 unpacking tar archive at: mozilla-central-b69a5bbb5e40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 09:28:53.863818) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:53.864184) ========= script_repo_revision: b69a5bbb5e40bd426e35222baa600b481e50d265 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:53.864615) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:53.864939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 09:28:53.883303) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-29 09:28:53.883618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:28:54 INFO - MultiFileLogger online at 20160629 09:28:54 in /builds/slave/test 09:28:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:28:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:54 INFO - {'append_to_log': False, 09:28:54 INFO - 'base_work_dir': '/builds/slave/test', 09:28:54 INFO - 'blob_upload_branch': 'Firefox', 09:28:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:54 INFO - 'branch': 'Firefox', 09:28:54 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:54 INFO - 'config_files': ('talos/mac_config.py',), 09:28:54 INFO - 'default_actions': ('clobber', 09:28:54 INFO - 'read-buildbot-config', 09:28:54 INFO - 'download-and-extract', 09:28:54 INFO - 'populate-webroot', 09:28:54 INFO - 'create-virtualenv', 09:28:54 INFO - 'install', 09:28:54 INFO - 'run-tests'), 09:28:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:54 INFO - 'download_minidump_stackwalk': True, 09:28:54 INFO - 'download_symbols': 'ondemand', 09:28:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:54 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:54 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:54 INFO - 'installer_path': 'installer.exe', 09:28:54 INFO - 'log_level': 'info', 09:28:54 INFO - 'log_name': 'talos', 09:28:54 INFO - 'log_to_console': True, 09:28:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:28:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:28:54 INFO - 'opt_config_files': (), 09:28:54 INFO - 'pip_index': False, 09:28:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:54 INFO - 'cmd': ('bash', 09:28:54 INFO - '-c', 09:28:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:54 INFO - 'enabled': True, 09:28:54 INFO - 'halt_on_failure': False, 09:28:54 INFO - 'name': 'check_screen_resolution'},), 09:28:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:54 INFO - 'cmd': ('bash', 09:28:54 INFO - '-c', 09:28:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:54 INFO - 'enabled': True, 09:28:54 INFO - 'halt_on_failure': False, 09:28:54 INFO - 'name': 'check_screen_resolution'},), 09:28:54 INFO - 'run_cmd_checks_enabled': True, 09:28:54 INFO - 'sps_profile': False, 09:28:54 INFO - 'sps_profile_interval': 0, 09:28:54 INFO - 'suite': 'dromaeojs', 09:28:54 INFO - 'system_bits': '32', 09:28:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:54 INFO - 'title': 't-yosemite-r7-0121', 09:28:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:54 INFO - 'use_talos_json': True, 09:28:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:54 INFO - 'work_dir': 'build'} 09:28:54 INFO - ##### 09:28:54 INFO - ##### Running clobber step. 09:28:54 INFO - ##### 09:28:54 INFO - Running pre-action listener: _resource_record_pre_action 09:28:54 INFO - Running main action method: clobber 09:28:54 INFO - rmtree: /builds/slave/test/build 09:28:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:56 INFO - Running post-action listener: _resource_record_post_action 09:28:56 INFO - ##### 09:28:56 INFO - ##### Finished clobber step (success) 09:28:56 INFO - ##### 09:28:56 INFO - ##### 09:28:56 INFO - ##### Running read-buildbot-config step. 09:28:56 INFO - ##### 09:28:56 INFO - Running pre-action listener: _resource_record_pre_action 09:28:56 INFO - Running main action method: read_buildbot_config 09:28:56 INFO - Using buildbot properties: 09:28:56 INFO - { 09:28:56 INFO - "project": "", 09:28:56 INFO - "product": "firefox", 09:28:56 INFO - "script_repo_revision": "production", 09:28:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:28:56 INFO - "repository": "", 09:28:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:28:56 INFO - "buildid": "20160629071428", 09:28:56 INFO - "pgo_build": "False", 09:28:56 INFO - "basedir": "/builds/slave/test", 09:28:56 INFO - "buildnumber": 35, 09:28:56 INFO - "slavename": "t-yosemite-r7-0121", 09:28:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:28:56 INFO - "platform": "yosemite_r7", 09:28:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:28:56 INFO - "branch": "mozilla-central", 09:28:56 INFO - "repo_path": "mozilla-central", 09:28:56 INFO - "revision": "b69a5bbb5e40bd426e35222baa600b481e50d265", 09:28:56 INFO - "stage_platform": "macosx64", 09:28:56 INFO - "builduid": "3ce221c54d684cfe9ee4af296fec3836", 09:28:56 INFO - "slavebuilddir": "test" 09:28:56 INFO - } 09:28:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:28:56 INFO - Running post-action listener: _resource_record_post_action 09:28:56 INFO - ##### 09:28:56 INFO - ##### Finished read-buildbot-config step (success) 09:28:56 INFO - ##### 09:28:56 INFO - ##### 09:28:56 INFO - ##### Running download-and-extract step. 09:28:56 INFO - ##### 09:28:56 INFO - Running pre-action listener: _resource_record_pre_action 09:28:56 INFO - Running main action method: download_and_extract 09:28:56 INFO - mkdir: /builds/slave/test/build/tests 09:28: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:28:56 INFO - https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:28:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:28:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:28:58 INFO - Downloaded 1199 bytes. 09:28:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:58 INFO - Using the following test package requirements: 09:28:58 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:28:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:28:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'jsshell-mac.zip'], 09:28:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:28:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:28:58 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:28:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:28:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:58 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:28:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:28: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:28:58 INFO - https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:28:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:28:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:28:59 INFO - Downloaded 18788519 bytes. 09:28:59 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:28:59 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:29:03 INFO - Return code: 0 09:29:03 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:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:03 INFO - https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:29:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:29:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:29:04 INFO - Downloaded 18788519 bytes. 09:29:04 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:29:04 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:29:07 INFO - Return code: 0 09:29: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:29:07 INFO - https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:29:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:29:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:29:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:29:08 INFO - Downloaded 14405245 bytes. 09:29:08 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:29:08 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:29:09 INFO - Return code: 0 09:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:09 INFO - https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:29:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q7sl7_kmSQWpyf04jZjOzw/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:29:12 INFO - Downloaded 112481150 bytes. 09:29:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:12 INFO - mkdir: /builds/slave/test/properties 09:29:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:12 INFO - Contents: 09:29:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:12 INFO - Running post-action listener: _resource_record_post_action 09:29:12 INFO - Running post-action listener: set_extra_try_arguments 09:29:12 INFO - ##### 09:29:12 INFO - ##### Finished download-and-extract step (success) 09:29:12 INFO - ##### 09:29:12 INFO - ##### 09:29:12 INFO - ##### Running populate-webroot step. 09:29:12 INFO - ##### 09:29:12 INFO - Running pre-action listener: _resource_record_pre_action 09:29:12 INFO - Running main action method: populate_webroot 09:29:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:12 INFO - '--tppagecycles', 09:29:12 INFO - '7'], 09:29:12 INFO - 'tests': ['tsvgm']}}, 09:29:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['damp', 'tps']}, 09:29:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'tests': ['damp', 'tps']}, 09:29:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['dromaeo_dom']}, 09:29:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['basic_compositor_video']}, 09:29:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['a11yr', 09:29:12 INFO - 'ts_paint', 09:29:12 INFO - 'tpaint', 09:29:12 INFO - 'sessionrestore', 09:29:12 INFO - 'sessionrestore_no_auto_restore', 09:29:12 INFO - 'tabpaint']}, 09:29:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['tsvgx', 09:29:12 INFO - 'tsvgr_opacity', 09:29:12 INFO - 'tart', 09:29:12 INFO - 'tscrollx', 09:29:12 INFO - 'cart']}, 09:29:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:12 INFO - 'tsvgr_opacity', 09:29:12 INFO - 'tart', 09:29:12 INFO - 'tscrollx', 09:29:12 INFO - 'cart']}, 09:29:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'talos_options': ['--disable-e10s'], 09:29:12 INFO - 'tests': ['tp5o']}, 09:29:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'tests': ['tp5o']}, 09:29:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'talos_options': ['--disable-e10s', 09:29:12 INFO - '--xperf_path', 09:29:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:12 INFO - 'tests': ['tp5n']}, 09:29:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:12 INFO - 'talos_options': ['--xperf_path', 09:29:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:12 INFO - 'tests': ['tp5n']}}, 09:29:12 INFO - 'talos.zip': {'path': '', 09:29:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:12 INFO - Running post-action listener: _resource_record_post_action 09:29:12 INFO - ##### 09:29:12 INFO - ##### Finished populate-webroot step (success) 09:29:12 INFO - ##### 09:29:12 INFO - ##### 09:29:12 INFO - ##### Running create-virtualenv step. 09:29:12 INFO - ##### 09:29:12 INFO - Running pre-action listener: _resource_record_pre_action 09:29:12 INFO - Running main action method: create_virtualenv 09:29:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:12 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:29:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:13 INFO - Using real prefix '/tools/python27' 09:29:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:16 INFO - Installing pip.................done. 09:29:16 INFO - Return code: 0 09:29:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29: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:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29: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', 'pip>=1.5'] in /builds/slave/test/build 09:29: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 pip>=1.5 09:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:16 INFO - 'HOME': '/Users/cltbld', 09:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:16 INFO - 'LOGNAME': 'cltbld', 09:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:16 INFO - 'MOZ_NO_REMOTE': '1', 09:29:16 INFO - 'NO_EM_RESTART': '1', 09:29:16 INFO - 'PAGER': '/bin/cat', 09:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:16 INFO - 'PWD': '/builds/slave/test', 09:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:16 INFO - 'SHELL': '/bin/bash', 09:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:16 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:16 INFO - 'USER': 'cltbld', 09:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:16 INFO - 'XPC_FLAGS': '0x0', 09:29:16 INFO - 'XPC_SERVICE_NAME': '0', 09:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:16 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:29:16 INFO - Cleaning up... 09:29:16 INFO - Return code: 0 09:29:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:29: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:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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', '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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:29: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 psutil>=3.1.1 09:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:16 INFO - 'HOME': '/Users/cltbld', 09:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:16 INFO - 'LOGNAME': 'cltbld', 09:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:16 INFO - 'MOZ_NO_REMOTE': '1', 09:29:16 INFO - 'NO_EM_RESTART': '1', 09:29:16 INFO - 'PAGER': '/bin/cat', 09:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:16 INFO - 'PWD': '/builds/slave/test', 09:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:16 INFO - 'SHELL': '/bin/bash', 09:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:16 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:16 INFO - 'USER': 'cltbld', 09:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:16 INFO - 'XPC_FLAGS': '0x0', 09:29:16 INFO - 'XPC_SERVICE_NAME': '0', 09:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:17 INFO - Downloading/unpacking psutil>=3.1.1 09:29: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:29: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:29: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:29: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:29: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:29: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:29:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:20 INFO - Installing collected packages: psutil 09:29:20 INFO - Running setup.py install for psutil 09:29:20 INFO - building 'psutil._psutil_osx' extension 09:29:20 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:29:20 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:29:20 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:29:20 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:29:21 INFO - building 'psutil._psutil_posix' extension 09:29:21 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:29:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:29:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:29:21 INFO - ^ 09:29:21 INFO - 1 warning generated. 09:29:21 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:29:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:21 INFO - Successfully installed psutil 09:29:21 INFO - Cleaning up... 09:29:21 INFO - Return code: 0 09:29:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:29: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:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:21 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:21 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:29:21 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:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:21 INFO - 'HOME': '/Users/cltbld', 09:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:21 INFO - 'LOGNAME': 'cltbld', 09:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:21 INFO - 'MOZ_NO_REMOTE': '1', 09:29:21 INFO - 'NO_EM_RESTART': '1', 09:29:21 INFO - 'PAGER': '/bin/cat', 09:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:21 INFO - 'PWD': '/builds/slave/test', 09:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:21 INFO - 'SHELL': '/bin/bash', 09:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:21 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:21 INFO - 'USER': 'cltbld', 09:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:21 INFO - 'XPC_FLAGS': '0x0', 09:29:21 INFO - 'XPC_SERVICE_NAME': '0', 09:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:29:21 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:29:21 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:29:21 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:29:21 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:29:21 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:29:21 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:29:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:29:24 INFO - Installing collected packages: mozsystemmonitor 09:29:24 INFO - Running setup.py install for mozsystemmonitor 09:29:24 INFO - Successfully installed mozsystemmonitor 09:29:24 INFO - Cleaning up... 09:29:24 INFO - Return code: 0 09:29:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:24 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:24 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:29:24 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:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:24 INFO - 'HOME': '/Users/cltbld', 09:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:24 INFO - 'LOGNAME': 'cltbld', 09:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:24 INFO - 'MOZ_NO_REMOTE': '1', 09:29:24 INFO - 'NO_EM_RESTART': '1', 09:29:24 INFO - 'PAGER': '/bin/cat', 09:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:24 INFO - 'PWD': '/builds/slave/test', 09:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:24 INFO - 'SHELL': '/bin/bash', 09:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:24 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:24 INFO - 'USER': 'cltbld', 09:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:24 INFO - 'XPC_FLAGS': '0x0', 09:29:24 INFO - 'XPC_SERVICE_NAME': '0', 09:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:27 INFO - warning: no files found matching '*.txt' 09:29:27 INFO - no previously-included directories found matching 'build' 09:29:27 INFO - no previously-included directories found matching 'dist' 09:29:27 INFO - no previously-included directories found matching '.git*' 09:29:27 INFO - Installing collected packages: jsonschema, functools32 09:29:27 INFO - Running setup.py install for functools32 09:29:27 INFO - warning: no files found matching '*.txt' 09:29:27 INFO - no previously-included directories found matching 'build' 09:29:27 INFO - no previously-included directories found matching 'dist' 09:29:27 INFO - no previously-included directories found matching '.git*' 09:29:27 INFO - Successfully installed jsonschema functools32 09:29:27 INFO - Cleaning up... 09:29:27 INFO - Return code: 0 09:29:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:27 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:27 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:29:27 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:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:27 INFO - 'HOME': '/Users/cltbld', 09:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:27 INFO - 'LOGNAME': 'cltbld', 09:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:27 INFO - 'MOZ_NO_REMOTE': '1', 09:29:27 INFO - 'NO_EM_RESTART': '1', 09:29:27 INFO - 'PAGER': '/bin/cat', 09:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:27 INFO - 'PWD': '/builds/slave/test', 09:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:27 INFO - 'SHELL': '/bin/bash', 09:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:27 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:27 INFO - 'USER': 'cltbld', 09:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:27 INFO - 'XPC_FLAGS': '0x0', 09:29:27 INFO - 'XPC_SERVICE_NAME': '0', 09:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:29: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:29: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:29: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:29: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:29: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:29: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:29:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:31 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:29:31 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:29:31 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:29:31 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:29:31 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:29:31 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:29:31 INFO - Downloading docopt-0.6.1.tar.gz 09:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:31 INFO - Running setup.py install for blobuploader 09:29:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:31 INFO - Running setup.py install for requests 09:29:32 INFO - Running setup.py install for docopt 09:29:32 INFO - Successfully installed blobuploader requests docopt 09:29:32 INFO - Cleaning up... 09:29:32 INFO - Return code: 0 09:29:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:32 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:32 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:29:32 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:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:32 INFO - 'HOME': '/Users/cltbld', 09:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:32 INFO - 'LOGNAME': 'cltbld', 09:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:32 INFO - 'MOZ_NO_REMOTE': '1', 09:29:32 INFO - 'NO_EM_RESTART': '1', 09:29:32 INFO - 'PAGER': '/bin/cat', 09:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:32 INFO - 'PWD': '/builds/slave/test', 09:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:32 INFO - 'SHELL': '/bin/bash', 09:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:32 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:32 INFO - 'USER': 'cltbld', 09:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:32 INFO - 'XPC_FLAGS': '0x0', 09:29:32 INFO - 'XPC_SERVICE_NAME': '0', 09:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:32 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-npGLnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Ce3YIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-0qxV1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-4qgUpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-apEn6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-yv_ZS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-k3fEnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Uk0T2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KMsGop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:33 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-gmtyxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-N3gfjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-AEkCpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-MhtW9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-eiD2Sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-MNjM7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-jJu9xK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:34 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Qeoz50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:34 INFO - Running setup.py install for manifestparser 09:29:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:34 INFO - Running setup.py install for mozcrash 09:29:35 INFO - Running setup.py install for mozdebug 09:29:35 INFO - Running setup.py install for mozdevice 09:29:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozfile 09:29:35 INFO - Running setup.py install for mozhttpd 09:29:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozinfo 09:29:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozInstall 09:29:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:35 INFO - Running setup.py install for mozleak 09:29:36 INFO - Running setup.py install for mozlog 09:29:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for moznetwork 09:29:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozprocess 09:29:36 INFO - Running setup.py install for mozprofile 09:29:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozrunner 09:29:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:36 INFO - Running setup.py install for mozscreenshot 09:29:37 INFO - Running setup.py install for moztest 09:29:37 INFO - Running setup.py install for mozversion 09:29:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:37 INFO - Cleaning up... 09:29:37 INFO - Return code: 0 09:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:37 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:37 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:29:37 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:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:37 INFO - 'HOME': '/Users/cltbld', 09:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:37 INFO - 'LOGNAME': 'cltbld', 09:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:37 INFO - 'MOZ_NO_REMOTE': '1', 09:29:37 INFO - 'NO_EM_RESTART': '1', 09:29:37 INFO - 'PAGER': '/bin/cat', 09:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:37 INFO - 'PWD': '/builds/slave/test', 09:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:37 INFO - 'SHELL': '/bin/bash', 09:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:37 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:37 INFO - 'USER': 'cltbld', 09:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:37 INFO - 'XPC_FLAGS': '0x0', 09:29:37 INFO - 'XPC_SERVICE_NAME': '0', 09:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:37 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-U4P_gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:37 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-PckNof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:37 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-eoErK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:37 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:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:37 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-TU_x07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-BVXdzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-6gPJC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-moYju_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ZzKSBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-mrfkdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-iVMcTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-qvRxhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:38 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-bA_EH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:38 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-8ZFIep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ZsJZw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-jXPzWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9tfey1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:39 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:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:39 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-NkGKxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:41 INFO - Downloading blessings-1.6.tar.gz 09:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:41 INFO - Installing collected packages: blessings 09:29:41 INFO - Running setup.py install for blessings 09:29:42 INFO - Successfully installed blessings 09:29:42 INFO - Cleaning up... 09:29:42 INFO - Return code: 0 09:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:42 INFO - Reading from file tmpfile_stdout 09:29:42 INFO - Current package versions: 09:29:42 INFO - blessings == 1.6 09:29:42 INFO - blobuploader == 1.2.4 09:29:42 INFO - docopt == 0.6.1 09:29:42 INFO - functools32 == 3.2.3-2 09:29:42 INFO - jsonschema == 2.5.1 09:29:42 INFO - manifestparser == 1.1 09:29:42 INFO - mozInstall == 1.12 09:29:42 INFO - mozcrash == 0.17 09:29:42 INFO - mozdebug == 0.1 09:29:42 INFO - mozdevice == 0.48 09:29:42 INFO - mozfile == 1.2 09:29:42 INFO - mozhttpd == 0.7 09:29:42 INFO - mozinfo == 0.9 09:29:42 INFO - mozleak == 0.1 09:29:42 INFO - mozlog == 3.2 09:29:42 INFO - moznetwork == 0.27 09:29:42 INFO - mozprocess == 0.22 09:29:42 INFO - mozprofile == 0.28 09:29:42 INFO - mozrunner == 6.11 09:29:42 INFO - mozscreenshot == 0.1 09:29:42 INFO - mozsystemmonitor == 0.3 09:29:42 INFO - moztest == 0.7 09:29:42 INFO - mozversion == 1.4 09:29:42 INFO - psutil == 3.1.1 09:29:42 INFO - requests == 1.2.3 09:29:42 INFO - wsgiref == 0.1.2 09:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 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 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:42 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:29:42 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:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:42 INFO - 'HOME': '/Users/cltbld', 09:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:42 INFO - 'LOGNAME': 'cltbld', 09:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:42 INFO - 'MOZ_NO_REMOTE': '1', 09:29:42 INFO - 'NO_EM_RESTART': '1', 09:29:42 INFO - 'PAGER': '/bin/cat', 09:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:42 INFO - 'PWD': '/builds/slave/test', 09:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:42 INFO - 'SHELL': '/bin/bash', 09:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:42 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:42 INFO - 'USER': 'cltbld', 09:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:42 INFO - 'XPC_FLAGS': '0x0', 09:29:42 INFO - 'XPC_SERVICE_NAME': '0', 09:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 INFO - Cleaning up... 09:29:42 INFO - Return code: 0 09:29:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ca2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d37618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff380c6e9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, '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.yNlOzWvU03/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.EdnJqfHAmW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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:29:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:42 INFO - 'HOME': '/Users/cltbld', 09:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:42 INFO - 'LOGNAME': 'cltbld', 09:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:42 INFO - 'MOZ_NO_REMOTE': '1', 09:29:42 INFO - 'NO_EM_RESTART': '1', 09:29:42 INFO - 'PAGER': '/bin/cat', 09:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:42 INFO - 'PWD': '/builds/slave/test', 09:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:42 INFO - 'SHELL': '/bin/bash', 09:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:29:42 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:42 INFO - 'USER': 'cltbld', 09:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:42 INFO - 'XPC_FLAGS': '0x0', 09:29:42 INFO - 'XPC_SERVICE_NAME': '0', 09:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:29:43 INFO - Cleaning up... 09:29:43 INFO - Return code: 0 09:29:43 INFO - Running post-action listener: _resource_record_post_action 09:29:43 INFO - Running post-action listener: _start_resource_monitoring 09:29:43 INFO - Starting resource monitoring. 09:29:43 INFO - ##### 09:29:43 INFO - ##### Finished create-virtualenv step (success) 09:29:43 INFO - ##### 09:29:43 INFO - ##### 09:29:43 INFO - ##### Running install step. 09:29:43 INFO - ##### 09:29:43 INFO - Running pre-action listener: _resource_record_pre_action 09:29:43 INFO - Running main action method: install 09:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:43 INFO - Reading from file tmpfile_stdout 09:29:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:43 INFO - Reading from file tmpfile_stdout 09:29:43 INFO - Output received: 09:29:43 INFO - Usage: mozinstall [options] installer 09:29:43 INFO - Options: 09:29:43 INFO - -h, --help show this help message and exit 09:29:43 INFO - -d DEST, --destination=DEST 09:29:43 INFO - Directory to install application into. [default: 09:29:43 INFO - "/builds/slave/test"] 09:29:43 INFO - --app=APP Application being installed. [default: firefox] 09:29:43 INFO - mkdir: /builds/slave/test/build/application 09:29:43 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:29:43 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:30:17 INFO - Reading from file tmpfile_stdout 09:30:17 INFO - Output received: 09:30:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:17 INFO - Running post-action listener: _resource_record_post_action 09:30:17 INFO - ##### 09:30:17 INFO - ##### Finished install step (success) 09:30:17 INFO - ##### 09:30:17 INFO - ##### 09:30:17 INFO - ##### Running run-tests step. 09:30:17 INFO - ##### 09:30:17 INFO - Running pre-action listener: _resource_record_pre_action 09:30:17 INFO - Running main action method: run_tests 09:30:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:17 INFO - 2016-06-29 09:30:17.843 screenresolution[1641:7675] starting screenresolution argv=screenresolution get 09:30:17 INFO - 2016-06-29 09:30:17.846 screenresolution[1641:7675] Display 0: 1600x1200x32@60 09:30:17 INFO - 2016-06-29 09:30:17.852 screenresolution[1642:7677] starting screenresolution argv=screenresolution list 09:30:17 INFO - Available Modes on Display 0 09:30:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:18 INFO - Intel Iris: 09:30:18 INFO - Chipset Model: Intel Iris 09:30:18 INFO - Type: GPU 09:30:18 INFO - Bus: Built-In 09:30:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:18 INFO - Vendor: Intel (0x8086) 09:30:18 INFO - Device ID: 0x0a2e 09:30:18 INFO - Revision ID: 0x0009 09:30:18 INFO - Displays: 09:30:18 INFO - Display: 09:30:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:18 INFO - Display Serial Number: 1600x1200 60 09:30:18 INFO - Main Display: Yes 09:30:18 INFO - Mirror: Off 09:30:18 INFO - Online: Yes 09:30:18 INFO - Rotation: Supported 09:30:18 INFO - Return code: 0 09:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:18 INFO - Python 2.7.3 09:30:18 INFO - Return code: 0 09:30:18 INFO - grabbing minidump binary from tooltool 09:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:18 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 0x103c06d30>, '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 0x10396bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103bfeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:18 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:30:18 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:30:18 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:30:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:18 INFO - Return code: 0 09:30:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:30:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:30:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0121 --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:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yNlOzWvU03/Render', 09:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:18 INFO - 'HOME': '/Users/cltbld', 09:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:18 INFO - 'LOGNAME': 'cltbld', 09:30:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:18 INFO - 'MOZ_NO_REMOTE': '1', 09:30:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:18 INFO - 'NO_EM_RESTART': '1', 09:30:18 INFO - 'PAGER': '/bin/cat', 09:30:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:18 INFO - 'PWD': '/builds/slave/test', 09:30:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:18 INFO - 'SHELL': '/bin/bash', 09:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EdnJqfHAmW/Listeners', 09:30:18 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 09:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:18 INFO - 'USER': 'cltbld', 09:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:18 INFO - 'XPC_FLAGS': '0x0', 09:30:18 INFO - 'XPC_SERVICE_NAME': '0', 09:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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:30:18 INFO - mozversion application_buildid: 20160629071428 09:30:18 INFO - mozversion application_changeset: b69a5bbb5e40bd426e35222baa600b481e50d265 09:30:18 INFO - mozversion application_display_name: Nightly 09:30:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:18 INFO - mozversion application_name: Firefox 09:30:18 INFO - mozversion application_remotingname: firefox 09:30:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:30:18 INFO - mozversion application_vendor: Mozilla 09:30:18 INFO - mozversion application_version: 50.0a1 09:30:18 INFO - mozversion platform_buildid: 20160629071428 09:30:18 INFO - mozversion platform_changeset: b69a5bbb5e40bd426e35222baa600b481e50d265 09:30:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:30:18 INFO - mozversion platform_version: 50.0a1 09:30:18 INFO - using testdate: 1467217818 09:30:18 INFO - actual date: 1467217818 09:30:18 INFO - starting webserver on 'localhost:49282' 09:30:18 INFO - SUITE-START | Running 2 tests 09:30:18 INFO - TEST-START | dromaeo_css 09:30:18 INFO - operating with platform_type : mac_ 09:30:18 INFO - Initialising browser for dromaeo_css test... 09:30:18 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmp0XbI25/profile http://localhost:49282/getInfo.html) 09:30:20 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:30:20 INFO - PROCESS | 1652 | colorDepth:24 09:30:20 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:30:20 INFO - PROCESS | 1652 | __metrics 09:30:20 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:30:20 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:30:21 INFO - TEST-INFO | 1652: exit 0 09:30:21 INFO - Browser initialized. 09:30:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:21 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmp0XbI25/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:33 INFO - PROCESS | 1654 | RSS: Main: 243449856 09:30:33 INFO - PROCESS | 1654 | 09:31:25 INFO - PROCESS | 1654 | 1467217885189 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:33:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49282/tests/dromaeo/cssquery-ext.html) 09:33:37 INFO - PROCESS | 1654 | RSS: Main: 371965952 09:33:37 INFO - PROCESS | 1654 | 09:36:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-ext.html (next: http://localhost:49282/tests/dromaeo/cssquery-jquery.html) 09:36:40 INFO - PROCESS | 1654 | RSS: Main: 370503680 09:36:40 INFO - PROCESS | 1654 | 09:39:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49282/tests/dromaeo/cssquery-mootools.html) 09:39:43 INFO - PROCESS | 1654 | RSS: Main: 368345088 09:39:43 INFO - PROCESS | 1654 | 09:42:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49282/tests/dromaeo/cssquery-prototype.html) 09:42:46 INFO - PROCESS | 1654 | RSS: Main: 370982912 09:42:46 INFO - PROCESS | 1654 | 09:45:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49282/tests/dromaeo/cssquery-yui.html) 09:45:50 INFO - PROCESS | 1654 | RSS: Main: 338452480 09:45:50 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49282/tests/dromaeo/cssquery-yui.html (next: http://localhost:49282/tests/dromaeo/cssquery-dojo.html) 09:48:53 INFO - PROCESS | 1654 | RSS: Main: 387006464 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | __start_tp_report 09:48:53 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:48:53 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:48:53 INFO - PROCESS | 1654 | |i|pagename|runs| 09:48:53 INFO - PROCESS | 1654 | |0;dojo.html;3252.2844974105697;3336.4995250712395;3371.477420999746;3399.1672040350118;3434.7423943204267;5573;5576.414476479967;5638.238837756901;5665.971670141644;5674.2623458950275;5600.215969764236;5632.633878797865;5686.289213848269;5700.515456186229;5745.482906538411;5049.77276022578;5077.974610126944;5106.846794596168;5115.335006449156;5225.294585230983;5215.374155101393;5221.112410890139;5238.528532432099;5253.50091741284;5269.130593452095;5073.162928116856;5092.465291144414;5097.84706458805;5120.743962801845;5162.122439185328;5092.694438333675;5098.898022039539;5105.081085404626;5107.412647545542;5110.8466745997675;5052.747362631853;5076.720780357079;5135.589152867761;5139.460356662534;5152.793888244503;5530.972345138289;5571.582131340166;5657.773689052433;5677.318721753375;5754.251947246873;6088.908666370008;6132.049532322497;6137.079438084278;6157.692115394212;6166;5488.91766623501;6095.207623008981;6196.256449226076;6214.689265536705;6232.875342493125;4541.750203738826;5596.496315331599;5620.213170156181;5753.482186603226;5784.652920824764;6208.81373558794;6254.718537665816;6256.968715156395;6261.405166509175;6264.780732674334;6079.574429789872;6090.604110732788;6099.461111717566;6103.907734867059;6139.508839292846;5966.075258334965;6113.358097399709;6115.785947491905;6131.079279648101;6142.262928448615;5703.230063941315;6100.2896813504785;6144.754209831646;6153.384661533901;6177.81466556004;6124.808163753374;6132.93684452123;6138.6623735694075;6158.661273629993;6166.568340216231;4799.9760001199775;4850.393700787402;4870.123377791961;4903.5586797188425;4965.428975667773;4347.630451411602;4360.520342762294;4385.0133719912765;4387;4408.801603927831;5665.518430933417;5668.489835914705;5677.659340439587;5693.772249110072;5745.052066408995;6112.633242005494;6115.449609535163;6122.764110201795;6189.226346706661;6189.6286222826775;5675.829725108253;5681.57388195887;5689.829305120853;5712.685802280831;5725.885482290331;5356.0894647909945;5361.892762144736;5368.302120724281;5379.569634429236;5383.730813459311;5087.694738315713;6097.0710051834385;6172.598781079216;6181.907271391023;6215.471684906744;6189.6286222826775;6199.16311297969;6200.7519699211525;6204.937950620436;6245.687715614292;6216.813495595139;6239.251289845248;6247.406496382836;6252.781152659635;6263.749450021948;5111.795528178831;6050.576459647782;6097.969510152421;6114.541409394402;6138.815835524942;6119.908201377072;6175.84560385994;6190.2571691397325;6206.286277078194;6211.627302361872;5813.912791308049;5861.179434879035;6047.939520604914;6059.787907423219;6062.272527296753;5830.2420685310635;5921.822345329647;5954.821355359346;5969.462748352669;5995.490383317467;5772.538196944319;5797.710114494174;5802.738876750472;5803.506701930383;5866.7066646668345;5846.473817356458;5938.376470470711;5956.344802071855;5971.790987315597;6031.065184896348;5848.356680765198;5850.0932355484965;5858.974679430998;5865.237519122486;5901.0558310670085 09:48:53 INFO - PROCESS | 1654 | |1;ext.html;437.130111078953;518.9014087323409;520.0898427751436;556.0491559433369;561.8174093419639;30965.69034309656;41807.16385672289;42741.93145171368;43026.56973430265;45112.3233151503;44356.3346549802;44589.777051114695;44752.77623611886;44957.10085798282;45132.548674513244;8561.0582835888;9549.283803714698;9727.513624318792;9733.124018838304;9737.269704772136;8590.012148602897;11648.009919156882;11905.523779048828;11926.165168438212;12165.939170304136;11930.105242106833;11965.58120465784;11969.341686207255;11980.520779168866;12001.939990300036;8634.956825215833;8828.984666763341;11793.7641247175;11870.406479676069;11918.165728399015;8735.257503112243;8795.47227166372;11498.540058397653;11824.408779560987;11827.526898924032;41152.79423602893;41518.792406038076;41644.167116657496;41649.79175104135;41667.583324166975;3463.826808659582;3614.765040272374;3763.9432923070785;3873.205992771503;4134.441850350225;31999.680003199905;32869.50695739566;32883.34233315344;32892.34215315704;32920.83539582311;3195.7985028691633;3199.168216263788;3311.8012919224925;3425.3149370125825;3428.8285585720614;493333;545038;545539.999999996;545602;547311;61275.69362153161;61708.691456543325;61826;61865.38134618596;61969;64474.6776266125;64601;64659.67670161713;64753.67623161854;65048.34951650518;15825.971311864692;16185.757213641804;16186.676266474604;16203.108829014518;16222;11260.605878794368;11600.955913967784;11621.128415368881;12295.631131066082;12670.429830657642;16542.917285413496;16644.834861559575;16655.3337866484;16661.250243738818;16712.74930876038;4855.587284961914;7144.571325720473;7415;7425.455158964554;7441.665125069385;895.7805337692307;1004.3672486333563;1082.6156714366286;1091.3779145886767;1144.164759725405;29506.55740163899;30769.69230307668;31193.220169495944;31489.527657085164;31587.52618710721;1065.8081545321916;1070.4915165296577;1083.6748975307376;1153.8499995000595;1164.3828770751516;16035.919820400823;16091.597710057342;16093.919530402272;16204.918975405048;16523.66952660964;705765;709404;710054;710178;715394;61473;61982.69008655018;61999.6900015497;62013.75972480525;62293;1229.7233122547354;1319.808627748971;1353.6209861238672;1389.8610138986223;1398.223985687953;32035.519467207545;32040;32214.83892580522;32443.026709198763;32752.836235818668;51838;51972.48027519828;52672;52689.47310526846;52694.73652631866;588129;593489.0325548345;594530;594710;597051;34392.96821095371;35586.64413355833;36643.63356366402;43300.78349608232;43535;49295;50535;50679;51201.7439912813;51260.74369628128;33664.49503257502;40663;41361.58638413577;41946.58053419427;42210.15579688451;40950;41566.58433415627;41613.95965100775;41932.37101443542;42378.576214238696;39965.80017099896;39980.600193997685;40709.185816282916;40848.79575602103;40953;29674.258143546584;30671.53992690156;35551.46672799962;36445.45331819972;36614.81692591627;23900.880495598107;26055.915526758192;26122.216333510027;26204.475910481302;26238.606420904085 09:48:53 INFO - PROCESS | 1654 | |2;jquery.html;320.74340527577937;344.79312412552457;382.1764098368017;387.49238497568206;403.9134727582802;15319;15417.845821541796;20841.791582084174;20867.852268125247;21544.138234470603;12295.893369596735;14487.275636218199;20099.899500502477;21117.472063198427;21123.049462774172;5165.199394093912;5360.678359298455;5398.6220964532495;5413.242146099549;5425.864353391177;3834.69322454203;4215.346621273708;5362.544183744387;6665.733370665168;6668.333166683317;6602.273749887532;6609.041688955099;6622.8675426491445;6633.104530888317;6638.3361663833475;6357.682115894187;6558.540902136852;6585.736570537173;6600.603963762142;6615.636140012298;5808.1578171165365;5832.533397328203;5839.562032847553;5841.240638716939;5844.1818145459665;13311.86688133116;13573.932130339384;18684.5052395808;25547;25727.228183154348;2026.929057482981;2098.9265375711775;2136.861104028233;2178.4662757624324;2214.5460180662926;10427.635032773893;12054.276743395425;12641.367931603476;14761.114333139938;15351.695105916011;1707.197617119964;1869.2990128701738;1872.7939926608062;1915.1477592471356;1940.9708854367195;122790.38604807008;123152;126120;131139;131316.6868331314;11597.18819682614;11614.88385116138;11700.590479333183;11745.236559623598;11829.52681892732;14542.854571454362;14655.48705795292;14655.92672036633;14691;14707.705845883022;8386.245237928615;8603.397762156714;8621.784455388666;8680.435771674822;8697;8415.11641277657;8522.488650680854;8567.485950842969;8568.614412351459;8579.528125953007;4464.821407143743;4542.091581683677;4545.409096817392;4623.653226008063;4643.187442197602;1532.440659159421;1814.4375243674504;1875.0812102069888;1931.478500804803;1962.6812726668206;377.6336953155435;378.8636091007235;867.3841572483482;919.1360121485782;938.4650749072963;11469.483873225725;11901.464434100486;11907.76184476323;11996.220245684002;12064.638060858188;417.13652738830933;429.24881457449465;438.9561043895585;447.3669757293458;447.9260921947907;6041.214642096587;6118.3269840317525;6214.875702486015;6223.346548612421;6227.875442491216;603593;607711;609803;610138;624890;82534;92378;120740.79259207471;140248;140286;413.89445691348516;437.2370213976631;444.5998601258828;445.3631307230691;445.42317698580416;10530.684079477627;11331.943340283246;11400.885991139983;11557;11599.304041757185;56780.43219567917;57620.13569796373;57727;58475;58583;74745.25254747384;76886.23113768791;78538.6073069631;79330;80261;7152.284771522806;7441.293077157661;7531.3598344141365;7621.733239336597;7748.961255193688;12803.61589152327;13285.202887826761;13587.660308492366;13827.446902124204;13913.373898174405;11640.825387619023;11650.592229271935;11673.533058677898;11861.28832270032;11928.642140735792;11575.479103440197;11622.94188529052;11679.532818687157;11881;11896;9972.30193886421;11041.613543525938;11773.882261177621;12030.578929737418;12035.578754743541;8429.452085614415;8501.744947651581;8506.319494440553;8521.403501754818;8667.479951202948;11113.388763618079;11183.608573699881;11415.486303116213;11520.654380368602;11883.287002779862 09:48:53 INFO - PROCESS | 1654 | |3;mootools.html;1371.3760239091214;1569.9372025118992;1749.3265092939214;1903.2957805611925;1920.75030246068;8428.789280267989;8718.694845680402;8886.377953543246;8890;8907.599158037887;7821.296083352498;8089;8401.411901166906;8648.956755216232;8677.26243269323;2339.415146213447;2367.147826782357;2368.0646144772804;2368.6802281691926;2373.4422410733464;2335.4278201840575;2341.309313752439;2385.42749740062;2385.642153676945;2385.9045638174453;2083.2292051940804;2159.946001349971;2288.7139107611547;2357.964630530543;2359.268626725712;2345.8827058647;2347.835651504387;2370.0756704885107;2378.084437491561;2380.0479808076907;1978.2779285560666;2350.15394457995;2364.101641376283;2370.360002799252;2371.158238825227;8823.735287941372;8904.59929303183;8907.866382004275;8910.420822646573;8917.6878809242;5211.088059589557;5300.893982120375;5366.087765079946;5368.141097424394;5373.81191658294;3559.394902866519;3597.3165098631175;3625.5649322081254;3654.7807131571926;3667.504886840269;1088.4884104470887;1093.03812644872;1097.9505922233518;1118.3122379736485;1119.3172164979355;11867.228630139014;11873.52505899763;11877.287362758176;11908.523659053628;11917.284962902253;4556.498785133633;4671.43942726875;4809.254565542346;4811.3745213122775;4821.614270858323;9966.053224943618;9975.102240798218;9975.301728879054;9992.550335234931;10010.299279050541;5367.194920761917;5371.892562148735;5373.7850485980125;5375.113106337489;5382.434844341366;4712.246040633482;4920.3603531541385;4928.630352723489;4930.063287975273;4946.010797840447;3380.7295416366633;3491.493733408692;3812.599677033927;3850.6534411903062;3873.573906870242;967.4920666649924;980.578351308944;1100.9779804403868;1128.2779021426293;1149.3104137517423;676;752.3417010116149;803.5459965119688;878.3412440669498;965.3146266151111;5474.206240095164;5710;5805.390434004453;5954.285485741739;6065.5450841187085;945.7304668169676;961.5480724059681;973.8587904753771;976.4580657734883;980.9117179453883;3558.466230065459;3896.201278737852;4035.2736507428945;4042.6159514846045;4045.7774822384463;9451.527423628928;10336.793264134676;10725;10886.07468365182;10926.289791163548;9988.900110998942;10245.077942985166;10831.295965762358;10868;10886.07468365182;7861.764147075597;8075.273225409741;8075.83848323042;8093.554854482943;8095.676172953134;5902.675352855463;5926.970365148147;5955.642661440327;6107.4197951194565;6156.5998210116195;9610.375325603813;10202.795944080928;10214.897851021395;10232.283740138118;10234.6417875374;10183.745406364604;10298;10299.691009269734;10340.327878687862;10352.430616316175;5979.983402821444;6318.557700961072;6482.384173503509;7525.586092764732;7611.124720657195;7940.28537431634;8220.972378452694;8321.791955200926;8343.207395297437;8344.332453403835;6129.816105516842;7312.341889229995;7439.739609113655;7505.024346834876;7718.15100338951;7284.307990740871;7390;7430.739924102847;7462.216467271075;7578.848423031619;6996.825079373057;7020;7105.964470177616;7115.217326094022;7115.430765538848;4830.275458681226;4939.728314942713;5017.046761115376;5046.59627229828;5112.463191364853;4982.701037937735;4988.226824842155;5105.2597373381395;5127.743612819419;5228.895422091613 09:48:53 INFO - PROCESS | 1654 | |4;prototype.html;300.9954850677239;306.1794391032034;314.68059919182025;316.39094742620466;319.8528676808668;4991;5106.336176297081;5329.946700532993;5419.972900135499;5439.646422982503;4068.5524592294896;4954.97522512387;5140.460251673577;5415.323084614423;5473.972630136854;3461.532693086439;4166.270902592034;4183.6821401258585;4193.811278492475;4212.536620971706;3990.9800450997705;4181.770002649853;4212.852550160745;4218.0509385388195;4225.345071513921;3446.6208717041104;4118.217538667658;4154.709170358076;4184.347182862767;4211.473565804275;4166.958330416717;4171.1240639465595;4171.582841715835;4185.204811085884;4218.0720241547115;3202.4235637585225;3641.271745650854;3644.487537671863;3654.3970244909824;3685.355062864015;4017.4576432181575;5278.788848446057;5355.892882142335;5381.973090134564;5435.402105768362;4657.464391594976;4664.72011679297;4674;4697.436307643071;4711.081339138869;5371.946280537184;5742.54059675225;5752.36723960364;5778.884422311572;5806.5645076619;3783;3903.2193561287854;3906.882793516199;3908.925045612451;3911.1004468972296;5782.826515204551;6010.57925945184;6040.96979515104;6055.182550355689;6059.909101363483;3945;3950.5654378018394;3957.368350047244;3957.9604203957592;3959.821808018646;3389.474631432132;3554.537910071674;3556.822158892045;3892.5013869660384;3981.5620281768984;3889.8913809564137;4591.334256532784;4642.651801114863;4655.883602909887;4692.249240121632;4456.5766252205985;5735.971320143456;5772.9711351442975;5790.681512516768;5843.678597677083;1066.5147357952112;1213.0901823632275;1307.0066749270434;1330.3946704249543;1373.3888419653158;888.8577827547691;895.5925054100499;908.7228395339353;918.8943271523726;918.903515130915;4290.742555446684;4842.757862106881;4906.945006823478;5253.763580638881;5254.605904557097;4759.714417134914;5621.915671264935;5688.151350811003;5844.26946631671;5880.441358070976;4939.5554400104165;5208.5312321891215;5299.125644268653;5308.814191503356;5352.411234764173;2266.5240299536954;2298.793133604871;2384.2370441458565;2574.8841302141072;2591.7796987255915;4245.724027938174;4269.743815371087;5036.420811606712;5119.411267704261;5184.377874655065;4843.539863712942;4954.9009019819405;5078.968412634902;5505.247376311844;6114.205153330127;5379.946200537945;5433.619646624698;6023.680882860131;6264.901258078504;6266.874662506815;5019.422766381766;5078.9238161426865;5783.306003279633;5996.940030599638;6052.878942421039;5720.838502716076;5937.079752638348;5949.126025302016;5955.493783028491;5982.491488223375;5851.765929362778;5989.700514974321;6193.721282542387;6205.634760352716;6247.96876015617;3707.46140351751;3929.9607003929596;3967.2345806116;3967.9801600992964;3973.900652483711;4672.906541869212;5308.213188076291;5899.882002360015;5952.821415357546;5955.583109182316;5545.445455454423;5656.048663211185;5677.432256774289;5722.7395178904935;5722.7681204331175;5439.932825526274;5747.683877386784;5764.942350576608;5765.509931655856;5783.248177736868;4855.514448555116;5372.838814835562;5645.463680950304;5654.236678048411;5662.405447427961;4864.148773964472;5764.13537969308;5795.478406943395;5801.332846722512;5810.891173222306;4879.585235255043;5288.894222115613;5690;5698.971505142614;5700.774333518313 09:48:53 INFO - PROCESS | 1654 | |5;yui.html;593.7357875745294;657.1030543308383;688.6281408039658;792.6829268292682;824.6948629007268;15200;15707.293171807267;15725.370985160595;15733.527994160166;15746.448874289403;13877.444902203899;14185.148891066543;14338.713225735504;14679;14741.631459213526;862.4911302331623;880.0627331891548;882.3117967984982;883.8939327280735;885.5085427587686;752.1350446985955;867.4014929698519;877.0878286581947;883.3860466975455;884.8097659003345;922.7370199493146;924.7780532672177;925.3152667026419;926.2543652359848;928.9535523223846;919.1497864475367;921.1479381572052;924.3437159616681;926.6941909169958;928.2110206324637;687.3710554842313;688.769262297131;689.5793565924782;691.2430888177438;691.9862401581707;11012.559497620086;12855.357232138449;12870.485180592765;12923.22460652364;14757;14190.574282771533;14280.85719142816;14349.713005739932;14389.856101438956;14524.92737536316;11795.646130616095;12055.517779288817;12465;12465.937670311681;12501.624951251477;3721.813909304551;3863.5750067492554;3955.802209889494;3971.245463361952;3996.4005399190064;79349.20650793475;99602;107616.4619176907;108342.45828770884;109488.4525577375;10889.945550272198;11011.119110471143;11116.722081948015;11156.497957591928;11415.543378264943;21008.369748907557;21916.780832191795;22088.88955555244;22160;22533.549329013327;8049.436539442183;8146.511209327459;8190;8267.007959044833;8356.014562597638;4491.438570178728;4578.313253012008;4593.655475839326;4647.814087436533;4758.738269395148;3497.300539891981;3626.4741612466046;3626.9455958160647;3629.9818500907854;3634.345817752777;676.4284179868004;683.6342556732125;685.7874059041613;695.9199692035379;702.3327838553395;574.8218052403769;586.2525280267607;660.9260892398839;672.4653900149393;713.2724620886667;8299.085508443432;8837.867431988525;9404;9468.763280918032;9597.23222142227;553.9639923405047;584.4914924016133;653.3956090616161;683.4293365040247;693.6163447014818;3833.5591406987996;4664.65015123867;4667.649926255544;4701.294805779161;4762.78567464458;91272;112343.43828280806;114138;150156;151495.24252378888;112507;119807;119918.8008119925;121658;125859;106258;110407.44796275967;110747.44626276816;110774;112079.43960280146;8998.910010900068;9023.233025192932;9026.684066057656;9040.321975851706;9210.585523651585;42602.78698606487;47168;59919.40080599138;63314.162365273216;64462;71353.08086489189;89355.98750571908;91878;92031.07968920493;94339.52830235806;11542.884571154182;11665.59170429031;11698.94150529242;12194.329311887932;12207.69480762988;10883.401412922365;13106.2136271824;13194.447915469147;13225.867741322463;13329.266890321125;11811.114166437723;12202.450889710162;12254.509819607116;12398;12686.429110690226;11699.473523691284;12124.515019399125;12153.149279550347;12244.510219591473;12555.246685198917;11706.70733231676;11983.400829958642;12105.455254513745;12228;12530.624081277576;10032.799344013225;10077.244206684381;10201.84697229557;10526.00190839708;10783.946080269548;10425.632243813941;10536.051755342056;10706.678799635712;10715.571377144826;10848.349099054081 09:48:53 INFO - PROCESS | 1654 | __end_tp_report 09:48:53 INFO - PROCESS | 1654 | __start_cc_report 09:48:53 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1242 09:48:53 INFO - PROCESS | 1654 | __end_cc_report 09:48:53 INFO - PROCESS | 1654 | __startTimestamp1467218933978__endTimestamp 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:48:53 INFO - PROCESS | 1654 | Number of tests: 6 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5649.46 Median:5803.51 stddev:627.13 (10.8%) stddev-sans-first:600.20 09:48:53 INFO - PROCESS | 1654 | Values: 3252.3 3336.5 3371.5 3399.2 3434.7 5573.0 5576.4 5638.2 5666.0 5674.3 5600.2 5632.6 5686.3 5700.5 5745.5 5049.8 5078.0 5106.8 5115.3 5225.3 5215.4 5221.1 5238.5 5253.5 5269.1 5073.2 5092.5 5097.8 5120.7 5162.1 5092.7 5098.9 5105.1 5107.4 5110.8 5052.7 5076.7 5135.6 5139.5 5152.8 5531.0 5571.6 5657.8 5677.3 5754.3 6088.9 6132.0 6137.1 6157.7 6166.0 5488.9 6095.2 6196.3 6214.7 6232.9 4541.8 5596.5 5620.2 5753.5 5784.7 6208.8 6254.7 6257.0 6261.4 6264.8 6079.6 6090.6 6099.5 6103.9 6139.5 5966.1 6113.4 6115.8 6131.1 6142.3 5703.2 6100.3 6144.8 6153.4 6177.8 6124.8 6132.9 6138.7 6158.7 6166.6 4800.0 4850.4 4870.1 4903.6 4965.4 4347.6 4360.5 4385.0 4387.0 4408.8 5665.5 5668.5 5677.7 5693.8 5745.1 6112.6 6115.4 6122.8 6189.2 6189.6 5675.8 5681.6 5689.8 5712.7 5725.9 5356.1 5361.9 5368.3 5379.6 5383.7 5087.7 6097.1 6172.6 6181.9 6215.5 6189.6 6199.2 6200.8 6204.9 6245.7 6216.8 6239.3 6247.4 6252.8 6263.7 5111.8 6050.6 6098.0 6114.5 6138.8 6119.9 6175.8 6190.3 6206.3 6211.6 5813.9 5861.2 6047.9 6059.8 6062.3 5830.2 5921.8 5954.8 5969.5 5995.5 5772.5 5797.7 5802.7 5803.5 5866.7 5846.5 5938.4 5956.3 5971.8 6031.1 5848.4 5850.1 5859.0 5865.2 5901.1 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:75176.45 Median:40686.09 stddev:165099.08 (405.8%) stddev-sans-first:165466.86 09:48:53 INFO - PROCESS | 1654 | Values: 437.1 518.9 520.1 556.0 561.8 30965.7 41807.2 42741.9 43026.6 45112.3 44356.3 44589.8 44752.8 44957.1 45132.5 8561.1 9549.3 9727.5 9733.1 9737.3 8590.0 11648.0 11905.5 11926.2 12165.9 11930.1 11965.6 11969.3 11980.5 12001.9 8635.0 8829.0 11793.8 11870.4 11918.2 8735.3 8795.5 11498.5 11824.4 11827.5 41152.8 41518.8 41644.2 41649.8 41667.6 3463.8 3614.8 3763.9 3873.2 4134.4 31999.7 32869.5 32883.3 32892.3 32920.8 3195.8 3199.2 3311.8 3425.3 3428.8 493333.0 545038.0 545540.0 545602.0 547311.0 61275.7 61708.7 61826.0 61865.4 61969.0 64474.7 64601.0 64659.7 64753.7 65048.3 15826.0 16185.8 16186.7 16203.1 16222.0 11260.6 11601.0 11621.1 12295.6 12670.4 16542.9 16644.8 16655.3 16661.3 16712.7 4855.6 7144.6 7415.0 7425.5 7441.7 895.8 1004.4 1082.6 1091.4 1144.2 29506.6 30769.7 31193.2 31489.5 31587.5 1065.8 1070.5 1083.7 1153.8 1164.4 16035.9 16091.6 16093.9 16204.9 16523.7 705765.0 709404.0 710054.0 710178.0 715394.0 61473.0 61982.7 61999.7 62013.8 62293.0 1229.7 1319.8 1353.6 1389.9 1398.2 32035.5 32040.0 32214.8 32443.0 32752.8 51838.0 51972.5 52672.0 52689.5 52694.7 588129.0 593489.0 594530.0 594710.0 597051.0 34393.0 35586.6 36643.6 43300.8 43535.0 49295.0 50535.0 50679.0 51201.7 51260.7 33664.5 40663.0 41361.6 41946.6 42210.2 40950.0 41566.6 41614.0 41932.4 42378.6 39965.8 39980.6 40709.2 40848.8 40953.0 29674.3 30671.5 35551.5 36445.5 36614.8 23900.9 26055.9 26122.2 26204.5 26238.6 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34688.62 Median:380.52 stddev:102055.18 (26819.9%) stddev-sans-first:102308.84 09:48:53 INFO - PROCESS | 1654 | Values: 320.7 344.8 382.2 387.5 403.9 15319.0 15417.8 20841.8 20867.9 21544.1 12295.9 14487.3 20099.9 21117.5 21123.0 5165.2 5360.7 5398.6 5413.2 5425.9 3834.7 4215.3 5362.5 6665.7 6668.3 6602.3 6609.0 6622.9 6633.1 6638.3 6357.7 6558.5 6585.7 6600.6 6615.6 5808.2 5832.5 5839.6 5841.2 5844.2 13311.9 13573.9 18684.5 25547.0 25727.2 2026.9 2098.9 2136.9 2178.5 2214.5 10427.6 12054.3 12641.4 14761.1 15351.7 1707.2 1869.3 1872.8 1915.1 1941.0 122790.4 123152.0 126120.0 131139.0 131316.7 11597.2 11614.9 11700.6 11745.2 11829.5 14542.9 14655.5 14655.9 14691.0 14707.7 8386.2 8603.4 8621.8 8680.4 8697.0 8415.1 8522.5 8567.5 8568.6 8579.5 4464.8 4542.1 4545.4 4623.7 4643.2 1532.4 1814.4 1875.1 1931.5 1962.7 377.6 378.9 867.4 919.1 938.5 11469.5 11901.5 11907.8 11996.2 12064.6 417.1 429.2 439.0 447.4 447.9 6041.2 6118.3 6214.9 6223.3 6227.9 603593.0 607711.0 609803.0 610138.0 624890.0 82534.0 92378.0 120740.8 140248.0 140286.0 413.9 437.2 444.6 445.4 445.4 10530.7 11331.9 11400.9 11557.0 11599.3 56780.4 57620.1 57727.0 58475.0 58583.0 74745.3 76886.2 78538.6 79330.0 80261.0 7152.3 7441.3 7531.4 7621.7 7749.0 12803.6 13285.2 13587.7 13827.4 13913.4 11640.8 11650.6 11673.5 11861.3 11928.6 11575.5 11622.9 11679.5 11881.0 11896.0 9972.3 11041.6 11773.9 12030.6 12035.6 8429.5 8501.7 8506.3 8521.4 8667.5 11113.4 11183.6 11415.5 11520.7 11883.3 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5688.12 Median:5031.82 stddev:3206.65 (63.7%) stddev-sans-first:3199.23 09:48:53 INFO - PROCESS | 1654 | Values: 1371.4 1569.9 1749.3 1903.3 1920.8 8428.8 8718.7 8886.4 8890.0 8907.6 7821.3 8089.0 8401.4 8649.0 8677.3 2339.4 2367.1 2368.1 2368.7 2373.4 2335.4 2341.3 2385.4 2385.6 2385.9 2083.2 2159.9 2288.7 2358.0 2359.3 2345.9 2347.8 2370.1 2378.1 2380.0 1978.3 2350.2 2364.1 2370.4 2371.2 8823.7 8904.6 8907.9 8910.4 8917.7 5211.1 5300.9 5366.1 5368.1 5373.8 3559.4 3597.3 3625.6 3654.8 3667.5 1088.5 1093.0 1098.0 1118.3 1119.3 11867.2 11873.5 11877.3 11908.5 11917.3 4556.5 4671.4 4809.3 4811.4 4821.6 9966.1 9975.1 9975.3 9992.6 10010.3 5367.2 5371.9 5373.8 5375.1 5382.4 4712.2 4920.4 4928.6 4930.1 4946.0 3380.7 3491.5 3812.6 3850.7 3873.6 967.5 980.6 1101.0 1128.3 1149.3 676.0 752.3 803.5 878.3 965.3 5474.2 5710.0 5805.4 5954.3 6065.5 945.7 961.5 973.9 976.5 980.9 3558.5 3896.2 4035.3 4042.6 4045.8 9451.5 10336.8 10725.0 10886.1 10926.3 9988.9 10245.1 10831.3 10868.0 10886.1 7861.8 8075.3 8075.8 8093.6 8095.7 5902.7 5927.0 5955.6 6107.4 6156.6 9610.4 10202.8 10214.9 10232.3 10234.6 10183.7 10298.0 10299.7 10340.3 10352.4 5980.0 6318.6 6482.4 7525.6 7611.1 7940.3 8221.0 8321.8 8343.2 8344.3 6129.8 7312.3 7439.7 7505.0 7718.2 7284.3 7390.0 7430.7 7462.2 7578.8 6996.8 7020.0 7106.0 7115.2 7115.4 4830.3 4939.7 5017.0 5046.6 5112.5 4982.7 4988.2 5105.3 5127.7 5228.9 09:48:53 INFO - PROCESS | 1654 | 09:48:53 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4574.61 Median:5078.95 stddev:1440.42 (28.4%) stddev-sans-first:1408.29 09:48:54 INFO - PROCESS | 1654 | Values: 301.0 306.2 314.7 316.4 319.9 4991.0 5106.3 5329.9 5420.0 5439.6 4068.6 4955.0 5140.5 5415.3 5474.0 3461.5 4166.3 4183.7 4193.8 4212.5 3991.0 4181.8 4212.9 4218.1 4225.3 3446.6 4118.2 4154.7 4184.3 4211.5 4167.0 4171.1 4171.6 4185.2 4218.1 3202.4 3641.3 3644.5 3654.4 3685.4 4017.5 5278.8 5355.9 5382.0 5435.4 4657.5 4664.7 4674.0 4697.4 4711.1 5371.9 5742.5 5752.4 5778.9 5806.6 3783.0 3903.2 3906.9 3908.9 3911.1 5782.8 6010.6 6041.0 6055.2 6059.9 3945.0 3950.6 3957.4 3958.0 3959.8 3389.5 3554.5 3556.8 3892.5 3981.6 3889.9 4591.3 4642.7 4655.9 4692.2 4456.6 5736.0 5773.0 5790.7 5843.7 1066.5 1213.1 1307.0 1330.4 1373.4 888.9 895.6 908.7 918.9 918.9 4290.7 4842.8 4906.9 5253.8 5254.6 4759.7 5621.9 5688.2 5844.3 5880.4 4939.6 5208.5 5299.1 5308.8 5352.4 2266.5 2298.8 2384.2 2574.9 2591.8 4245.7 4269.7 5036.4 5119.4 5184.4 4843.5 4954.9 5079.0 5505.2 6114.2 5379.9 5433.6 6023.7 6264.9 6266.9 5019.4 5078.9 5783.3 5996.9 6052.9 5720.8 5937.1 5949.1 5955.5 5982.5 5851.8 5989.7 6193.7 6205.6 6248.0 3707.5 3930.0 3967.2 3968.0 3973.9 4672.9 5308.2 5899.9 5952.8 5955.6 5545.4 5656.0 5677.4 5722.7 5722.8 5439.9 5747.7 5764.9 5765.5 5783.2 4855.5 5372.8 5645.5 5654.2 5662.4 4864.1 5764.1 5795.5 5801.3 5810.9 4879.6 5288.9 5690.0 5699.0 5700.8 09:48:54 INFO - PROCESS | 1654 | 09:48:54 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:23129.85 Median:3632.16 stddev:36331.93 (1000.3%) stddev-sans-first:36394.44 09:48:54 INFO - PROCESS | 1654 | Values: 593.7 657.1 688.6 792.7 824.7 15200.0 15707.3 15725.4 15733.5 15746.4 13877.4 14185.1 14338.7 14679.0 14741.6 862.5 880.1 882.3 883.9 885.5 752.1 867.4 877.1 883.4 884.8 922.7 924.8 925.3 926.3 929.0 919.1 921.1 924.3 926.7 928.2 687.4 688.8 689.6 691.2 692.0 11012.6 12855.4 12870.5 12923.2 14757.0 14190.6 14280.9 14349.7 14389.9 14524.9 11795.6 12055.5 12465.0 12465.9 12501.6 3721.8 3863.6 3955.8 3971.2 3996.4 79349.2 99602.0 107616.5 108342.5 109488.5 10889.9 11011.1 11116.7 11156.5 11415.5 21008.4 21916.8 22088.9 22160.0 22533.5 8049.4 8146.5 8190.0 8267.0 8356.0 4491.4 4578.3 4593.7 4647.8 4758.7 3497.3 3626.5 3626.9 3630.0 3634.3 676.4 683.6 685.8 695.9 702.3 574.8 586.3 660.9 672.5 713.3 8299.1 8837.9 9404.0 9468.8 9597.2 554.0 584.5 653.4 683.4 693.6 3833.6 4664.7 4667.6 4701.3 4762.8 91272.0 112343.4 114138.0 150156.0 151495.2 112507.0 119807.0 119918.8 121658.0 125859.0 106258.0 110407.4 110747.4 110774.0 112079.4 8998.9 9023.2 9026.7 9040.3 9210.6 42602.8 47168.0 59919.4 63314.2 64462.0 71353.1 89356.0 91878.0 92031.1 94339.5 11542.9 11665.6 11698.9 12194.3 12207.7 10883.4 13106.2 13194.4 13225.9 13329.3 11811.1 12202.5 12254.5 12398.0 12686.4 11699.5 12124.5 12153.1 12244.5 12555.2 11706.7 11983.4 12105.5 12228.0 12530.6 10032.8 10077.2 10201.8 10526.0 10783.9 10425.6 10536.1 10706.7 10715.6 10848.3 09:48:54 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:48:54 INFO - PROCESS | 1654 | 09:48:54 INFO - TEST-INFO | 1654: exit 0 09:48:54 INFO - TEST-OK | dromaeo_css | took 1116284ms 09:48:54 INFO - TEST-START | kraken 09:48:54 INFO - operating with platform_type : mac_ 09:48:54 INFO - Initialising browser for kraken test... 09:48:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpsGVORR/profile http://localhost:49282/getInfo.html) 09:48:56 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 09:48:56 INFO - PROCESS | 1664 | colorDepth:24 09:48:56 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 09:48:56 INFO - PROCESS | 1664 | __metrics 09:48:56 INFO - PROCESS | 1664 | 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:48:56 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:57 INFO - TEST-INFO | 1664: exit 0 09:48:57 INFO - Browser initialized. 09:48:57 INFO - Running cycle 1/1 for kraken test... 09:48:57 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpsGVORR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:09 INFO - PROCESS | 1665 | RSS: Main: 244715520 09:49:09 INFO - PROCESS | 1665 | 09:49:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:13 INFO - PROCESS | 1665 | RSS: Main: 294170624 09:49:13 INFO - PROCESS | 1665 | 09:49:16 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-dft) 09:49:16 INFO - PROCESS | 1665 | RSS: Main: 304824320 09:49:16 INFO - PROCESS | 1665 | 09:49:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-fft) 09:49:20 INFO - PROCESS | 1665 | RSS: Main: 391692288 09:49:20 INFO - PROCESS | 1665 | 09:49:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator) 09:49:23 INFO - PROCESS | 1665 | RSS: Main: 291209216 09:49:23 INFO - PROCESS | 1665 | 09:49:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:26 INFO - PROCESS | 1665 | RSS: Main: 295153664 09:49:26 INFO - PROCESS | 1665 | 09:49:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:29 INFO - PROCESS | 1665 | RSS: Main: 331096064 09:49:29 INFO - PROCESS | 1665 | 09:49:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:33 INFO - PROCESS | 1665 | RSS: Main: 331403264 09:49:33 INFO - PROCESS | 1665 | 09:49:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial) 09:49:36 INFO - PROCESS | 1665 | RSS: Main: 330829824 09:49:36 INFO - PROCESS | 1665 | 09:49:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:38 INFO - PROCESS | 1665 | RSS: Main: 311590912 09:49:38 INFO - PROCESS | 1665 | 09:49:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:41 INFO - PROCESS | 1665 | RSS: Main: 318431232 09:49:41 INFO - PROCESS | 1665 | 09:49:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:44 INFO - PROCESS | 1665 | RSS: Main: 315723776 09:49:44 INFO - PROCESS | 1665 | 09:49:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:47 INFO - PROCESS | 1665 | RSS: Main: 326578176 09:49:47 INFO - PROCESS | 1665 | 09:49:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:51 INFO - PROCESS | 1665 | RSS: Main: 328273920 09:49:51 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49282/tests/kraken/driver.html?testName=ai-astar) 09:49:53 INFO - PROCESS | 1665 | RSS: Main: 371785728 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | __start_tp_report 09:49:53 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 09:49:53 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 09:49:53 INFO - PROCESS | 1665 | |i|pagename|runs| 09:49:53 INFO - PROCESS | 1665 | |0;ai-astar;103;114;116;113;119;164;119;113;113;111 09:49:53 INFO - PROCESS | 1665 | |1;audio-beat-detection;166;147;108;154;106;105;141;106;141;107 09:49:53 INFO - PROCESS | 1665 | |2;audio-dft;184;185;184;188;186;183;186;185;181;183 09:49:53 INFO - PROCESS | 1665 | |3;audio-fft;78;74;59;129;81;80;70;68;85;56 09:49:53 INFO - PROCESS | 1665 | |4;audio-oscillator;82;97;120;102;101;69;94;71;69;101 09:49:53 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;85;87;86;87;84;87;85;88;84 09:49:53 INFO - PROCESS | 1665 | |6;imaging-darkroom;95;110;152;103;96;94;95;95;93;93 09:49:53 INFO - PROCESS | 1665 | |7;imaging-desaturate;87;88;88;84;85;85;85;84;85;89 09:49:53 INFO - PROCESS | 1665 | |8;json-parse-financial;55;54;53;54;53;55;54;53;55;55 09:49:53 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;65;73;66;75;70;71;68;71;67;69 09:49:53 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;86;81;85;82;82;82;82;84;86;84 09:49:53 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;140;139;149;135;135;134;137;147;237;146 09:49:53 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;166;166;165;162;163;165;161;159;166;167 09:49:53 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;68;69;80;84;58;69;86;83;83 09:49:53 INFO - PROCESS | 1665 | __end_tp_report 09:49:53 INFO - PROCESS | 1665 | __start_cc_report 09:49:53 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2010 09:49:53 INFO - PROCESS | 1665 | __end_cc_report 09:49:53 INFO - PROCESS | 1665 | __startTimestamp1467218993331__endTimestamp 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | ------- Summary: start ------- 09:49:53 INFO - PROCESS | 1665 | Number of tests: 14 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:118.50 Median:115.00 stddev:16.61 (14.4%) stddev-sans-first:16.65 09:49:53 INFO - PROCESS | 1665 | Values: 103.0 114.0 116.0 113.0 119.0 164.0 119.0 113.0 113.0 111.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:141.00 stddev:23.94 (17.0%) stddev-sans-first:21.10 09:49:53 INFO - PROCESS | 1665 | Values: 166.0 147.0 108.0 154.0 106.0 105.0 141.0 106.0 141.0 107.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.96 (1.1%) stddev-sans-first:2.07 09:49:53 INFO - PROCESS | 1665 | Values: 184.0 185.0 184.0 188.0 186.0 183.0 186.0 185.0 181.0 183.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:78.00 Median:76.00 stddev:20.24 (26.6%) stddev-sans-first:21.47 09:49:53 INFO - PROCESS | 1665 | Values: 78.0 74.0 59.0 129.0 81.0 80.0 70.0 68.0 85.0 56.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:70.00 stddev:17.17 (24.5%) stddev-sans-first:17.93 09:49:53 INFO - PROCESS | 1665 | Values: 82.0 97.0 120.0 102.0 101.0 69.0 94.0 71.0 69.0 101.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.45 09:49:53 INFO - PROCESS | 1665 | Values: 87.0 85.0 87.0 86.0 87.0 84.0 87.0 85.0 88.0 84.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:94.50 stddev:18.17 (19.2%) stddev-sans-first:19.06 09:49:53 INFO - PROCESS | 1665 | Values: 95.0 110.0 152.0 103.0 96.0 94.0 95.0 95.0 93.0 93.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:1.83 (2.1%) stddev-sans-first:1.90 09:49:53 INFO - PROCESS | 1665 | Values: 87.0 88.0 88.0 84.0 85.0 85.0 85.0 84.0 85.0 89.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.87 09:49:53 INFO - PROCESS | 1665 | Values: 55.0 54.0 53.0 54.0 53.0 55.0 54.0 53.0 55.0 55.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.50 stddev:3.14 (4.4%) stddev-sans-first:2.87 09:49:53 INFO - PROCESS | 1665 | Values: 65.0 73.0 66.0 75.0 70.0 71.0 68.0 71.0 67.0 69.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:1.84 (2.2%) stddev-sans-first:1.69 09:49:53 INFO - PROCESS | 1665 | Values: 86.0 81.0 85.0 82.0 82.0 82.0 82.0 84.0 86.0 84.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:143.00 stddev:31.07 (21.7%) stddev-sans-first:32.75 09:49:53 INFO - PROCESS | 1665 | Values: 140.0 139.0 149.0 135.0 135.0 134.0 137.0 147.0 237.0 146.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.00 Median:165.50 stddev:2.62 (1.6%) stddev-sans-first:2.68 09:49:53 INFO - PROCESS | 1665 | Values: 166.0 166.0 165.0 162.0 163.0 165.0 161.0 159.0 166.0 167.0 09:49:53 INFO - PROCESS | 1665 | 09:49:53 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:81.50 stddev:9.59 (11.8%) stddev-sans-first:9.76 09:49:53 INFO - PROCESS | 1665 | Values: 67.0 68.0 69.0 80.0 84.0 58.0 69.0 86.0 83.0 83.0 09:49:53 INFO - PROCESS | 1665 | -------- Summary: end -------- 09:49:53 INFO - PROCESS | 1665 | 09:49:54 INFO - TEST-INFO | 1665: exit 0 09:49:54 INFO - TEST-OK | kraken | took 59446ms 09:49:54 INFO - SUITE-END | took 1175s 09:49:54 INFO - Completed test suite (00:19:36) 09:49:54 INFO - javascript benchmark 09:49:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.2844974105697, 3336.4995250712395, 3371.477420999746, 3399.1672040350118, 3434.7423943204267, 5573.0, 5576.414476479967, 5638.238837756901, 5665.971670141644, 5674.2623458950275, 5600.215969764236, 5632.633878797865, 5686.289213848269, 5700.515456186229, 5745.482906538411, 5049.77276022578, 5077.974610126944, 5106.846794596168, 5115.335006449156, 5225.294585230983, 5215.374155101393, 5221.112410890139, 5238.528532432099, 5253.50091741284, 5269.130593452095, 5073.162928116856, 5092.465291144414, 5097.84706458805, 5120.743962801845, 5162.122439185328, 5092.694438333675, 5098.898022039539, 5105.081085404626, 5107.412647545542, 5110.8466745997675, 5052.747362631853, 5076.720780357079, 5135.589152867761, 5139.460356662534, 5152.793888244503, 5530.972345138289, 5571.582131340166, 5657.773689052433, 5677.318721753375, 5754.251947246873, 6088.908666370008, 6132.049532322497, 6137.079438084278, 6157.692115394212, 6166.0, 5488.91766623501, 6095.207623008981, 6196.256449226076, 6214.689265536705, 6232.875342493125, 4541.750203738826, 5596.496315331599, 5620.213170156181, 5753.482186603226, 5784.652920824764, 6208.81373558794, 6254.718537665816, 6256.968715156395, 6261.405166509175, 6264.780732674334, 6079.574429789872, 6090.604110732788, 6099.461111717566, 6103.907734867059, 6139.508839292846, 5966.075258334965, 6113.358097399709, 6115.785947491905, 6131.079279648101, 6142.262928448615, 5703.230063941315, 6100.2896813504785, 6144.754209831646, 6153.384661533901, 6177.81466556004, 6124.808163753374, 6132.93684452123, 6138.6623735694075, 6158.661273629993, 6166.568340216231, 4799.9760001199775, 4850.393700787402, 4870.123377791961, 4903.5586797188425, 4965.428975667773, 4347.630451411602, 4360.520342762294, 4385.0133719912765, 4387.0, 4408.801603927831, 5665.518430933417, 5668.489835914705, 5677.659340439587, 5693.772249110072, 5745.052066408995, 6112.633242005494, 6115.449609535163, 6122.764110201795, 6189.226346706661, 6189.6286222826775, 5675.829725108253, 5681.57388195887, 5689.829305120853, 5712.685802280831, 5725.885482290331, 5356.0894647909945, 5361.892762144736, 5368.302120724281, 5379.569634429236, 5383.730813459311, 5087.694738315713, 6097.0710051834385, 6172.598781079216, 6181.907271391023, 6215.471684906744, 6189.6286222826775, 6199.16311297969, 6200.7519699211525, 6204.937950620436, 6245.687715614292, 6216.813495595139, 6239.251289845248, 6247.406496382836, 6252.781152659635, 6263.749450021948, 5111.795528178831, 6050.576459647782, 6097.969510152421, 6114.541409394402, 6138.815835524942, 6119.908201377072, 6175.84560385994, 6190.2571691397325, 6206.286277078194, 6211.627302361872, 5813.912791308049, 5861.179434879035, 6047.939520604914, 6059.787907423219, 6062.272527296753, 5830.2420685310635, 5921.822345329647, 5954.821355359346, 5969.462748352669, 5995.490383317467, 5772.538196944319, 5797.710114494174, 5802.738876750472, 5803.506701930383, 5866.7066646668345, 5846.473817356458, 5938.376470470711, 5956.344802071855, 5971.790987315597, 6031.065184896348, 5848.356680765198, 5850.0932355484965, 5858.974679430998, 5865.237519122486, 5901.0558310670085], "value": 5610.691566591565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.130111078953, 518.9014087323409, 520.0898427751436, 556.0491559433369, 561.8174093419639, 30965.69034309656, 41807.16385672289, 42741.93145171368, 43026.56973430265, 45112.3233151503, 44356.3346549802, 44589.777051114695, 44752.77623611886, 44957.10085798282, 45132.548674513244, 8561.0582835888, 9549.283803714698, 9727.513624318792, 9733.124018838304, 9737.269704772136, 8590.012148602897, 11648.009919156882, 11905.523779048828, 11926.165168438212, 12165.939170304136, 11930.105242106833, 11965.58120465784, 11969.341686207255, 11980.520779168866, 12001.939990300036, 8634.956825215833, 8828.984666763341, 11793.7641247175, 11870.406479676069, 11918.165728399015, 8735.257503112243, 8795.47227166372, 11498.540058397653, 11824.408779560987, 11827.526898924032, 41152.79423602893, 41518.792406038076, 41644.167116657496, 41649.79175104135, 41667.583324166975, 3463.826808659582, 3614.765040272374, 3763.9432923070785, 3873.205992771503, 4134.441850350225, 31999.680003199905, 32869.50695739566, 32883.34233315344, 32892.34215315704, 32920.83539582311, 3195.7985028691633, 3199.168216263788, 3311.8012919224925, 3425.3149370125825, 3428.8285585720614, 493333.0, 545038.0, 545539.999999996, 545602.0, 547311.0, 61275.69362153161, 61708.691456543325, 61826.0, 61865.38134618596, 61969.0, 64474.6776266125, 64601.0, 64659.67670161713, 64753.67623161854, 65048.34951650518, 15825.971311864692, 16185.757213641804, 16186.676266474604, 16203.108829014518, 16222.0, 11260.605878794368, 11600.955913967784, 11621.128415368881, 12295.631131066082, 12670.429830657642, 16542.917285413496, 16644.834861559575, 16655.3337866484, 16661.250243738818, 16712.74930876038, 4855.587284961914, 7144.571325720473, 7415.0, 7425.455158964554, 7441.665125069385, 895.7805337692307, 1004.3672486333563, 1082.6156714366286, 1091.3779145886767, 1144.164759725405, 29506.55740163899, 30769.69230307668, 31193.220169495944, 31489.527657085164, 31587.52618710721, 1065.8081545321916, 1070.4915165296577, 1083.6748975307376, 1153.8499995000595, 1164.3828770751516, 16035.919820400823, 16091.597710057342, 16093.919530402272, 16204.918975405048, 16523.66952660964, 705765.0, 709404.0, 710054.0, 710178.0, 715394.0, 61473.0, 61982.69008655018, 61999.6900015497, 62013.75972480525, 62293.0, 1229.7233122547354, 1319.808627748971, 1353.6209861238672, 1389.8610138986223, 1398.223985687953, 32035.519467207545, 32040.0, 32214.83892580522, 32443.026709198763, 32752.836235818668, 51838.0, 51972.48027519828, 52672.0, 52689.47310526846, 52694.73652631866, 588129.0, 593489.0325548345, 594530.0, 594710.0, 597051.0, 34392.96821095371, 35586.64413355833, 36643.63356366402, 43300.78349608232, 43535.0, 49295.0, 50535.0, 50679.0, 51201.7439912813, 51260.74369628128, 33664.49503257502, 40663.0, 41361.58638413577, 41946.58053419427, 42210.15579688451, 40950.0, 41566.58433415627, 41613.95965100775, 41932.37101443542, 42378.576214238696, 39965.80017099896, 39980.600193997685, 40709.185816282916, 40848.79575602103, 40953.0, 29674.258143546584, 30671.53992690156, 35551.46672799962, 36445.45331819972, 36614.81692591627, 23900.880495598107, 26055.915526758192, 26122.216333510027, 26204.475910481302, 26238.606420904085], "value": 21033.11659282168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.74340527577937, 344.79312412552457, 382.1764098368017, 387.49238497568206, 403.9134727582802, 15319.0, 15417.845821541796, 20841.791582084174, 20867.852268125247, 21544.138234470603, 12295.893369596735, 14487.275636218199, 20099.899500502477, 21117.472063198427, 21123.049462774172, 5165.199394093912, 5360.678359298455, 5398.6220964532495, 5413.242146099549, 5425.864353391177, 3834.69322454203, 4215.346621273708, 5362.544183744387, 6665.733370665168, 6668.333166683317, 6602.273749887532, 6609.041688955099, 6622.8675426491445, 6633.104530888317, 6638.3361663833475, 6357.682115894187, 6558.540902136852, 6585.736570537173, 6600.603963762142, 6615.636140012298, 5808.1578171165365, 5832.533397328203, 5839.562032847553, 5841.240638716939, 5844.1818145459665, 13311.86688133116, 13573.932130339384, 18684.5052395808, 25547.0, 25727.228183154348, 2026.929057482981, 2098.9265375711775, 2136.861104028233, 2178.4662757624324, 2214.5460180662926, 10427.635032773893, 12054.276743395425, 12641.367931603476, 14761.114333139938, 15351.695105916011, 1707.197617119964, 1869.2990128701738, 1872.7939926608062, 1915.1477592471356, 1940.9708854367195, 122790.38604807008, 123152.0, 126120.0, 131139.0, 131316.6868331314, 11597.18819682614, 11614.88385116138, 11700.590479333183, 11745.236559623598, 11829.52681892732, 14542.854571454362, 14655.48705795292, 14655.92672036633, 14691.0, 14707.705845883022, 8386.245237928615, 8603.397762156714, 8621.784455388666, 8680.435771674822, 8697.0, 8415.11641277657, 8522.488650680854, 8567.485950842969, 8568.614412351459, 8579.528125953007, 4464.821407143743, 4542.091581683677, 4545.409096817392, 4623.653226008063, 4643.187442197602, 1532.440659159421, 1814.4375243674504, 1875.0812102069888, 1931.478500804803, 1962.6812726668206, 377.6336953155435, 378.8636091007235, 867.3841572483482, 919.1360121485782, 938.4650749072963, 11469.483873225725, 11901.464434100486, 11907.76184476323, 11996.220245684002, 12064.638060858188, 417.13652738830933, 429.24881457449465, 438.9561043895585, 447.3669757293458, 447.9260921947907, 6041.214642096587, 6118.3269840317525, 6214.875702486015, 6223.346548612421, 6227.875442491216, 603593.0, 607711.0, 609803.0, 610138.0, 624890.0, 82534.0, 92378.0, 120740.79259207471, 140248.0, 140286.0, 413.89445691348516, 437.2370213976631, 444.5998601258828, 445.3631307230691, 445.42317698580416, 10530.684079477627, 11331.943340283246, 11400.885991139983, 11557.0, 11599.304041757185, 56780.43219567917, 57620.13569796373, 57727.0, 58475.0, 58583.0, 74745.25254747384, 76886.23113768791, 78538.6073069631, 79330.0, 80261.0, 7152.284771522806, 7441.293077157661, 7531.3598344141365, 7621.733239336597, 7748.961255193688, 12803.61589152327, 13285.202887826761, 13587.660308492366, 13827.446902124204, 13913.373898174405, 11640.825387619023, 11650.592229271935, 11673.533058677898, 11861.28832270032, 11928.642140735792, 11575.479103440197, 11622.94188529052, 11679.532818687157, 11881.0, 11896.0, 9972.30193886421, 11041.613543525938, 11773.882261177621, 12030.578929737418, 12035.578754743541, 8429.452085614415, 8501.744947651581, 8506.319494440553, 8521.403501754818, 8667.479951202948, 11113.388763618079, 11183.608573699881, 11415.486303116213, 11520.654380368602, 11883.287002779862], "value": 8692.068976753344, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.3760239091214, 1569.9372025118992, 1749.3265092939214, 1903.2957805611925, 1920.75030246068, 8428.789280267989, 8718.694845680402, 8886.377953543246, 8890.0, 8907.599158037887, 7821.296083352498, 8089.0, 8401.411901166906, 8648.956755216232, 8677.26243269323, 2339.415146213447, 2367.147826782357, 2368.0646144772804, 2368.6802281691926, 2373.4422410733464, 2335.4278201840575, 2341.309313752439, 2385.42749740062, 2385.642153676945, 2385.9045638174453, 2083.2292051940804, 2159.946001349971, 2288.7139107611547, 2357.964630530543, 2359.268626725712, 2345.8827058647, 2347.835651504387, 2370.0756704885107, 2378.084437491561, 2380.0479808076907, 1978.2779285560666, 2350.15394457995, 2364.101641376283, 2370.360002799252, 2371.158238825227, 8823.735287941372, 8904.59929303183, 8907.866382004275, 8910.420822646573, 8917.6878809242, 5211.088059589557, 5300.893982120375, 5366.087765079946, 5368.141097424394, 5373.81191658294, 3559.394902866519, 3597.3165098631175, 3625.5649322081254, 3654.7807131571926, 3667.504886840269, 1088.4884104470887, 1093.03812644872, 1097.9505922233518, 1118.3122379736485, 1119.3172164979355, 11867.228630139014, 11873.52505899763, 11877.287362758176, 11908.523659053628, 11917.284962902253, 4556.498785133633, 4671.43942726875, 4809.254565542346, 4811.3745213122775, 4821.614270858323, 9966.053224943618, 9975.102240798218, 9975.301728879054, 9992.550335234931, 10010.299279050541, 5367.194920761917, 5371.892562148735, 5373.7850485980125, 5375.113106337489, 5382.434844341366, 4712.246040633482, 4920.3603531541385, 4928.630352723489, 4930.063287975273, 4946.010797840447, 3380.7295416366633, 3491.493733408692, 3812.599677033927, 3850.6534411903062, 3873.573906870242, 967.4920666649924, 980.578351308944, 1100.9779804403868, 1128.2779021426293, 1149.3104137517423, 676.0, 752.3417010116149, 803.5459965119688, 878.3412440669498, 965.3146266151111, 5474.206240095164, 5710.0, 5805.390434004453, 5954.285485741739, 6065.5450841187085, 945.7304668169676, 961.5480724059681, 973.8587904753771, 976.4580657734883, 980.9117179453883, 3558.466230065459, 3896.201278737852, 4035.2736507428945, 4042.6159514846045, 4045.7774822384463, 9451.527423628928, 10336.793264134676, 10725.0, 10886.07468365182, 10926.289791163548, 9988.900110998942, 10245.077942985166, 10831.295965762358, 10868.0, 10886.07468365182, 7861.764147075597, 8075.273225409741, 8075.83848323042, 8093.554854482943, 8095.676172953134, 5902.675352855463, 5926.970365148147, 5955.642661440327, 6107.4197951194565, 6156.5998210116195, 9610.375325603813, 10202.795944080928, 10214.897851021395, 10232.283740138118, 10234.6417875374, 10183.745406364604, 10298.0, 10299.691009269734, 10340.327878687862, 10352.430616316175, 5979.983402821444, 6318.557700961072, 6482.384173503509, 7525.586092764732, 7611.124720657195, 7940.28537431634, 8220.972378452694, 8321.791955200926, 8343.207395297437, 8344.332453403835, 6129.816105516842, 7312.341889229995, 7439.739609113655, 7505.024346834876, 7718.15100338951, 7284.307990740871, 7390.0, 7430.739924102847, 7462.216467271075, 7578.848423031619, 6996.825079373057, 7020.0, 7105.964470177616, 7115.217326094022, 7115.430765538848, 4830.275458681226, 4939.728314942713, 5017.046761115376, 5046.59627229828, 5112.463191364853, 4982.701037937735, 4988.226824842155, 5105.2597373381395, 5127.743612819419, 5228.895422091613], "value": 4552.360595920341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.9954850677239, 306.1794391032034, 314.68059919182025, 316.39094742620466, 319.8528676808668, 4991.0, 5106.336176297081, 5329.946700532993, 5419.972900135499, 5439.646422982503, 4068.5524592294896, 4954.97522512387, 5140.460251673577, 5415.323084614423, 5473.972630136854, 3461.532693086439, 4166.270902592034, 4183.6821401258585, 4193.811278492475, 4212.536620971706, 3990.9800450997705, 4181.770002649853, 4212.852550160745, 4218.0509385388195, 4225.345071513921, 3446.6208717041104, 4118.217538667658, 4154.709170358076, 4184.347182862767, 4211.473565804275, 4166.958330416717, 4171.1240639465595, 4171.582841715835, 4185.204811085884, 4218.0720241547115, 3202.4235637585225, 3641.271745650854, 3644.487537671863, 3654.3970244909824, 3685.355062864015, 4017.4576432181575, 5278.788848446057, 5355.892882142335, 5381.973090134564, 5435.402105768362, 4657.464391594976, 4664.72011679297, 4674.0, 4697.436307643071, 4711.081339138869, 5371.946280537184, 5742.54059675225, 5752.36723960364, 5778.884422311572, 5806.5645076619, 3783.0, 3903.2193561287854, 3906.882793516199, 3908.925045612451, 3911.1004468972296, 5782.826515204551, 6010.57925945184, 6040.96979515104, 6055.182550355689, 6059.909101363483, 3945.0, 3950.5654378018394, 3957.368350047244, 3957.9604203957592, 3959.821808018646, 3389.474631432132, 3554.537910071674, 3556.822158892045, 3892.5013869660384, 3981.5620281768984, 3889.8913809564137, 4591.334256532784, 4642.651801114863, 4655.883602909887, 4692.249240121632, 4456.5766252205985, 5735.971320143456, 5772.9711351442975, 5790.681512516768, 5843.678597677083, 1066.5147357952112, 1213.0901823632275, 1307.0066749270434, 1330.3946704249543, 1373.3888419653158, 888.8577827547691, 895.5925054100499, 908.7228395339353, 918.8943271523726, 918.903515130915, 4290.742555446684, 4842.757862106881, 4906.945006823478, 5253.763580638881, 5254.605904557097, 4759.714417134914, 5621.915671264935, 5688.151350811003, 5844.26946631671, 5880.441358070976, 4939.5554400104165, 5208.5312321891215, 5299.125644268653, 5308.814191503356, 5352.411234764173, 2266.5240299536954, 2298.793133604871, 2384.2370441458565, 2574.8841302141072, 2591.7796987255915, 4245.724027938174, 4269.743815371087, 5036.420811606712, 5119.411267704261, 5184.377874655065, 4843.539863712942, 4954.9009019819405, 5078.968412634902, 5505.247376311844, 6114.205153330127, 5379.946200537945, 5433.619646624698, 6023.680882860131, 6264.901258078504, 6266.874662506815, 5019.422766381766, 5078.9238161426865, 5783.306003279633, 5996.940030599638, 6052.878942421039, 5720.838502716076, 5937.079752638348, 5949.126025302016, 5955.493783028491, 5982.491488223375, 5851.765929362778, 5989.700514974321, 6193.721282542387, 6205.634760352716, 6247.96876015617, 3707.46140351751, 3929.9607003929596, 3967.2345806116, 3967.9801600992964, 3973.900652483711, 4672.906541869212, 5308.213188076291, 5899.882002360015, 5952.821415357546, 5955.583109182316, 5545.445455454423, 5656.048663211185, 5677.432256774289, 5722.7395178904935, 5722.7681204331175, 5439.932825526274, 5747.683877386784, 5764.942350576608, 5765.509931655856, 5783.248177736868, 4855.514448555116, 5372.838814835562, 5645.463680950304, 5654.236678048411, 5662.405447427961, 4864.148773964472, 5764.13537969308, 5795.478406943395, 5801.332846722512, 5810.891173222306, 4879.585235255043, 5288.894222115613, 5690.0, 5698.971505142614, 5700.774333518313], "value": 4114.102443078569, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7357875745294, 657.1030543308383, 688.6281408039658, 792.6829268292682, 824.6948629007268, 15200.0, 15707.293171807267, 15725.370985160595, 15733.527994160166, 15746.448874289403, 13877.444902203899, 14185.148891066543, 14338.713225735504, 14679.0, 14741.631459213526, 862.4911302331623, 880.0627331891548, 882.3117967984982, 883.8939327280735, 885.5085427587686, 752.1350446985955, 867.4014929698519, 877.0878286581947, 883.3860466975455, 884.8097659003345, 922.7370199493146, 924.7780532672177, 925.3152667026419, 926.2543652359848, 928.9535523223846, 919.1497864475367, 921.1479381572052, 924.3437159616681, 926.6941909169958, 928.2110206324637, 687.3710554842313, 688.769262297131, 689.5793565924782, 691.2430888177438, 691.9862401581707, 11012.559497620086, 12855.357232138449, 12870.485180592765, 12923.22460652364, 14757.0, 14190.574282771533, 14280.85719142816, 14349.713005739932, 14389.856101438956, 14524.92737536316, 11795.646130616095, 12055.517779288817, 12465.0, 12465.937670311681, 12501.624951251477, 3721.813909304551, 3863.5750067492554, 3955.802209889494, 3971.245463361952, 3996.4005399190064, 79349.20650793475, 99602.0, 107616.4619176907, 108342.45828770884, 109488.4525577375, 10889.945550272198, 11011.119110471143, 11116.722081948015, 11156.497957591928, 11415.543378264943, 21008.369748907557, 21916.780832191795, 22088.88955555244, 22160.0, 22533.549329013327, 8049.436539442183, 8146.511209327459, 8190.0, 8267.007959044833, 8356.014562597638, 4491.438570178728, 4578.313253012008, 4593.655475839326, 4647.814087436533, 4758.738269395148, 3497.300539891981, 3626.4741612466046, 3626.9455958160647, 3629.9818500907854, 3634.345817752777, 676.4284179868004, 683.6342556732125, 685.7874059041613, 695.9199692035379, 702.3327838553395, 574.8218052403769, 586.2525280267607, 660.9260892398839, 672.4653900149393, 713.2724620886667, 8299.085508443432, 8837.867431988525, 9404.0, 9468.763280918032, 9597.23222142227, 553.9639923405047, 584.4914924016133, 653.3956090616161, 683.4293365040247, 693.6163447014818, 3833.5591406987996, 4664.65015123867, 4667.649926255544, 4701.294805779161, 4762.78567464458, 91272.0, 112343.43828280806, 114138.0, 150156.0, 151495.24252378888, 112507.0, 119807.0, 119918.8008119925, 121658.0, 125859.0, 106258.0, 110407.44796275967, 110747.44626276816, 110774.0, 112079.43960280146, 8998.910010900068, 9023.233025192932, 9026.684066057656, 9040.321975851706, 9210.585523651585, 42602.78698606487, 47168.0, 59919.40080599138, 63314.162365273216, 64462.0, 71353.08086489189, 89355.98750571908, 91878.0, 92031.07968920493, 94339.52830235806, 11542.884571154182, 11665.59170429031, 11698.94150529242, 12194.329311887932, 12207.69480762988, 10883.401412922365, 13106.2136271824, 13194.447915469147, 13225.867741322463, 13329.266890321125, 11811.114166437723, 12202.450889710162, 12254.509819607116, 12398.0, 12686.429110690226, 11699.473523691284, 12124.515019399125, 12153.149279550347, 12244.510219591473, 12555.246685198917, 11706.70733231676, 11983.400829958642, 12105.455254513745, 12228.0, 12530.624081277576, 10032.799344013225, 10077.244206684381, 10201.84697229557, 10526.00190839708, 10783.946080269548, 10425.632243813941, 10536.051755342056, 10706.678799635712, 10715.571377144826, 10848.349099054081], "value": 7607.190750251276, "unit": "score"}], "name": "dromaeo_css", "value": 7257.789695549432, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 116.0, 113.0, 119.0, 164.0, 119.0, 113.0, 113.0, 111.0], "value": 118.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 147.0, 108.0, 154.0, 106.0, 105.0, 141.0, 106.0, 141.0, 107.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 188.0, 186.0, 183.0, 186.0, 185.0, 181.0, 183.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 59.0, 129.0, 81.0, 80.0, 70.0, 68.0, 85.0, 56.0], "value": 78.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 97.0, 120.0, 102.0, 101.0, 69.0, 94.0, 71.0, 69.0, 101.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 86.0, 87.0, 84.0, 87.0, 85.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 110.0, 152.0, 103.0, 96.0, 94.0, 95.0, 95.0, 93.0, 93.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 84.0, 85.0, 85.0, 85.0, 84.0, 85.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 53.0, 55.0, 54.0, 53.0, 55.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 66.0, 75.0, 70.0, 71.0, 68.0, 71.0, 67.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 85.0, 82.0, 82.0, 82.0, 82.0, 84.0, 86.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 149.0, 135.0, 135.0, 134.0, 137.0, 147.0, 237.0, 146.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 165.0, 162.0, 163.0, 165.0, 161.0, 159.0, 166.0, 167.0], "value": 164.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 80.0, 84.0, 58.0, 69.0, 86.0, 83.0, 83.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1469.9000000000003, "alertThreshold": 2.0}]} 09:49:54 INFO - Return code: 0 09:49:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:49:54 INFO - # TBPL SUCCESS # 09:49:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:54 INFO - 2016-06-29 09:49:54.593 screenresolution[1669:9923] starting screenresolution argv=screenresolution get 09:49:54 INFO - 2016-06-29 09:49:54.596 screenresolution[1669:9923] Display 0: 1600x1200x32@60 09:49:54 INFO - 2016-06-29 09:49:54.602 screenresolution[1670:9926] starting screenresolution argv=screenresolution list 09:49:54 INFO - Available Modes on Display 0 09:49:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:54 INFO - Intel Iris: 09:49:54 INFO - Chipset Model: Intel Iris 09:49:54 INFO - Type: GPU 09:49:54 INFO - Bus: Built-In 09:49:54 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:54 INFO - Vendor: Intel (0x8086) 09:49:54 INFO - Device ID: 0x0a2e 09:49:54 INFO - Revision ID: 0x0009 09:49:54 INFO - Displays: 09:49:54 INFO - Display: 09:49:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:54 INFO - Display Serial Number: 1600x1200 60 09:49:54 INFO - Main Display: Yes 09:49:54 INFO - Mirror: Off 09:49:54 INFO - Online: Yes 09:49:54 INFO - Rotation: Supported 09:49:54 INFO - Return code: 0 09:49:54 INFO - Running post-action listener: _resource_record_post_action 09:49:54 INFO - ##### 09:49:54 INFO - ##### Finished run-tests step (success) 09:49:54 INFO - ##### 09:49:54 INFO - Running post-run listener: _resource_record_post_run 09:49:54 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37914112; Write bytes: 705054208; Read time: 501; Write time: 1871 09:49:54 INFO - TinderboxPrint: CPU usage
53.4% 09:49:54 INFO - TinderboxPrint: I/O read bytes / time
37,914,112 / 501 09:49:54 INFO - TinderboxPrint: I/O write bytes / time
705,054,208 / 1,871 09:49:54 INFO - TinderboxPrint: CPU idle
2,256.4 (46.6%) 09:49:54 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 09:49:54 INFO - TinderboxPrint: CPU user
1,619.6 (33.4%) 09:49:54 INFO - TinderboxPrint: Swap in / out
445,001,728 / 0 09:49:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390160896; Write bytes: 384423936; Read time: 29130; Write time: 710 09:49:54 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36931072; Write bytes: 295341568; Read time: 485; Write time: 1111 09:49:55 INFO - Running post-run listener: _upload_blobber_files 09:49:55 INFO - Blob upload gear active. 09:49:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:55 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:49:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:49:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:49:55 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:56 INFO - (blobuploader) - INFO - Done attempting. 09:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:57 INFO - (blobuploader) - INFO - Done attempting. 09:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:00 INFO - (blobuploader) - INFO - Done attempting. 09:50:00 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:00 INFO - Return code: 0 09:50:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63d7b51ad1966a12f29da928e6263aa9a2e0171758b95375919fcc2e82e297262394086bac43061ad02e4e5c79866c3906deffdeeefad65f0dcc34cb0b54fa18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb717998ace4baf4b6818e325d72fab0877ceeb56f0ff90518196f77713dc25c1f724b7a16db4a429136dc2a89ff57a3071494d1731cc4c813b3d11aae3069fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43721f3890b41aac7ece2b768e9ba96006be2dd96888ace29f42b2bf47f39703c732e08d774a37ba2bb5c115c2f2f8afba48f624f0849103740cdc369cb0fbe4"} 09:50:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:00 INFO - Contents: 09:50:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63d7b51ad1966a12f29da928e6263aa9a2e0171758b95375919fcc2e82e297262394086bac43061ad02e4e5c79866c3906deffdeeefad65f0dcc34cb0b54fa18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb717998ace4baf4b6818e325d72fab0877ceeb56f0ff90518196f77713dc25c1f724b7a16db4a429136dc2a89ff57a3071494d1731cc4c813b3d11aae3069fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43721f3890b41aac7ece2b768e9ba96006be2dd96888ace29f42b2bf47f39703c732e08d774a37ba2bb5c115c2f2f8afba48f624f0849103740cdc369cb0fbe4"} 09:50:00 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:00 INFO - Copying logs to upload dir... 09:50:00 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.227999 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-29 09:50:00.150570) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:50:00.154166) ========= 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.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63d7b51ad1966a12f29da928e6263aa9a2e0171758b95375919fcc2e82e297262394086bac43061ad02e4e5c79866c3906deffdeeefad65f0dcc34cb0b54fa18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb717998ace4baf4b6818e325d72fab0877ceeb56f0ff90518196f77713dc25c1f724b7a16db4a429136dc2a89ff57a3071494d1731cc4c813b3d11aae3069fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43721f3890b41aac7ece2b768e9ba96006be2dd96888ace29f42b2bf47f39703c732e08d774a37ba2bb5c115c2f2f8afba48f624f0849103740cdc369cb0fbe4"} build_url:https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/Q7sl7_kmSQWpyf04jZjOzw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63d7b51ad1966a12f29da928e6263aa9a2e0171758b95375919fcc2e82e297262394086bac43061ad02e4e5c79866c3906deffdeeefad65f0dcc34cb0b54fa18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb717998ace4baf4b6818e325d72fab0877ceeb56f0ff90518196f77713dc25c1f724b7a16db4a429136dc2a89ff57a3071494d1731cc4c813b3d11aae3069fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43721f3890b41aac7ece2b768e9ba96006be2dd96888ace29f42b2bf47f39703c732e08d774a37ba2bb5c115c2f2f8afba48f624f0849103740cdc369cb0fbe4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 09:50:00.187921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:50:00.188318) ========= 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.yNlOzWvU03/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.EdnJqfHAmW/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 09:50:00.237926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:50:00.238295) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 09:50:00.238697) ========= ========= Total master_lag: 0.17 =========