Ambari automatic registration failed (Step 3: Confirm Hosts) with (NetUtil.py:96 - EOF occurred in violation of protocol (_ssl.c:579))

[#1]

We were trying to install HDP-2.6.4.0 using Ambari – 2.6.1.5 We got the following error at the step of comfirm hosts.

INFO 2018-09-07 12:51:45,461 DataCleaner.py:122 - Data cleanup finished
INFO 2018-09-07 12:51:45,461 hostname.py:67 - agent:hostname_script configuration not defined thus read hostname '***.net' using socket.getfqdn().
INFO 2018-09-07 12:51:45,595 PingPortListener.py:50 - Ping port listener started on port: 8670
INFO 2018-09-07 12:51:45,598 main.py:437 - Connecting to Ambari server at https://***.net:8440 (10.215.102.157)
INFO 2018-09-07 12:51:45,598 NetUtil.py:70 - Connecting to https://***:8440/ca
ERROR 2018-09-07 12:51:45,601 NetUtil.py:96 - EOF occurred in violation of protocol (_ssl.c:579)
ERROR 2018-09-07 12:51:45,601 NetUtil.py:97 - SSLError: Failed to connect. Please check openssl library versions. 
Refer to: https://bugzilla.redhat.com/show_bug.cgi?id=1022468 for more details.
WARNING 2018-09-07 12:51:45,601 NetUtil.py:124 - Server at https://***:8440 is not reachable, sleeping for 10 seconds...
", None)

*** is our website and sorry that due to the company policy we have to replace it with wild card

[#2]

Hi @jessica Chen!
Did you look at this solution?
https://community.hortonworks.com/questions/145/openssl-error-upon-host-registration.html

Hope this helps!

[#3]

Hi @jessica Chen ,

This blog similar to the issue you are facing : https://community.hortonworks.com/articles/188269/javapython-updates-and-ambari-agent-tls-settings.h...

Please see if this helps you . Please accept answer if it did

[#4]

Hi Akhil and Vincius,

Thanks a lot for the help! Yes after I used the solution indicated here the error message disappeared. However, the registration still failed. I attached the log below.

==========================

Creating target directory...

==========================

Command start time 2018-09-10 17:15:36

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:36

==========================

Copying ambari sudo script...

==========================

Command start time 2018-09-10 17:15:36

scp /var/lib/ambari-server/ambari-sudo.sh

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:36

==========================

Copying common functions script...

==========================

Command start time 2018-09-10 17:15:36

scp /usr/lib/python2.6/site-packages/ambari_commons

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:37

==========================

Copying create-python-wrap script...

==========================

Command start time 2018-09-10 17:15:37

scp /var/lib/ambari-server/create-python-wrap.sh

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:37

==========================

Copying OS type check script...

==========================

Command start time 2018-09-10 17:15:37

scp /usr/lib/python2.6/site-packages/ambari_server/os_check_type.py

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:38

==========================

Running create-python-wrap script...

==========================

Command start time 2018-09-10 17:15:38

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:38

==========================

Running OS type check...

==========================

Command start time 2018-09-10 17:15:38

Cluster primary/cluster OS family is redhat7 and local/current OS family is redhat7

*******************************************************************************

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:38

==========================

Checking 'sudo' package on remote host...

==========================

Command start time 2018-09-10 17:15:38

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:39

==========================

Copying repo file to 'tmp' folder...

==========================

Command start time 2018-09-10 17:15:39

scp /etc/yum.repos.d/ambari.repo

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:39

==========================

Moving file to repo dir...

==========================

Command start time 2018-09-10 17:15:39

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:39

==========================

Changing permissions for ambari.repo...

==========================

Command start time 2018-09-10 17:15:39

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:40

==========================

Copying setup script file...

==========================

Command start time 2018-09-10 17:15:40

scp /usr/lib/python2.6/site-packages/ambari_server/setupAgent.py

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:40

==========================

Running setup agent script...

==========================

Command start time 2018-09-10 17:15:40

("INFO 2018-09-10 17:15:48,906 security.py:93 - SSL Connect being called.. connecting to the server

INFO 2018-09-10 17:15:48,962 security.py:60 - SSL connection established. Two-way SSL authentication is turned off on the server.

INFO 2018-09-10 17:15:49,065 Controller.py:196 - Registration Successful (response id = 0)

INFO 2018-09-10 17:15:49,066 ClusterConfiguration.py:119 - Updating cached configurations for cluster ***_***

INFO 2018-09-10 17:15:49,079 RecoveryManager.py:577 - RecoverConfig = {u'components': u'',

u'maxCount': u'6',

u'maxLifetimeCount': u'1024',

u'recoveryTimestamp': 1536614149004,

u'retryGap': u'5',

u'type': u'AUTO_START',

u'windowInMinutes': u'60'}

INFO 2018-09-10 17:15:49,079 RecoveryManager.py:677 - ==> Auto recovery is enabled with maximum 6 in 60 minutes with gap of 5 minutes between and lifetime max being 1024. Enabled components -

INFO 2018-09-10 17:15:49,079 AmbariConfig.py:316 - Updating config property (agent.check.remote.mounts) with value (false)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (agent.auto.cache.update) with value (true)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (java.home) with value (/usr/jdk64/jdk1.8.0_112)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (agent.check.mounts.timeout) with value (0)

INFO 2018-09-10 17:15:49,085 AlertSchedulerHandler.py:291 - [AlertScheduler] Caching cluster ***_*** with alert hash c5ef37fa8872c86e4ca91019b1b160e3

INFO 2018-09-10 17:15:49,089 AlertSchedulerHandler.py:230 - [AlertScheduler] Reschedule Summary: 0 rescheduled, 0 unscheduled

INFO 2018-09-10 17:15:49,090 Controller.py:516 - Registration response from ***.***.net was OK

INFO 2018-09-10 17:15:49,090 Controller.py:521 - Resetting ActionQueue...

", None)

("INFO 2018-09-10 17:15:48,906 security.py:93 - SSL Connect being called.. connecting to the server

INFO 2018-09-10 17:15:48,962 security.py:60 - SSL connection established. Two-way SSL authentication is turned off on the server.

INFO 2018-09-10 17:15:49,065 Controller.py:196 - Registration Successful (response id = 0)

INFO 2018-09-10 17:15:49,066 ClusterConfiguration.py:119 - Updating cached configurations for cluster ***_***

INFO 2018-09-10 17:15:49,079 RecoveryManager.py:577 - RecoverConfig = {u'components': u'',

u'maxCount': u'6',

u'maxLifetimeCount': u'1024',

u'recoveryTimestamp': 1536614149004,

u'retryGap': u'5',

u'type': u'AUTO_START',

u'windowInMinutes': u'60'}

INFO 2018-09-10 17:15:49,079 RecoveryManager.py:677 - ==> Auto recovery is enabled with maximum 6 in 60 minutes with gap of 5 minutes between and lifetime max being 1024. Enabled components -

INFO 2018-09-10 17:15:49,079 AmbariConfig.py:316 - Updating config property (agent.check.remote.mounts) with value (false)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (agent.auto.cache.update) with value (true)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (java.home) with value (/usr/jdk64/jdk1.8.0_112)

INFO 2018-09-10 17:15:49,080 AmbariConfig.py:316 - Updating config property (agent.check.mounts.timeout) with value (0)

INFO 2018-09-10 17:15:49,085 AlertSchedulerHandler.py:291 - [AlertScheduler] Caching cluster ***_*** with alert hash c5ef37fa8872c86e4ca91019b1b160e3

INFO 2018-09-10 17:15:49,089 AlertSchedulerHandler.py:230 - [AlertScheduler] Reschedule Summary: 0 rescheduled, 0 unscheduled

INFO 2018-09-10 17:15:49,090 Controller.py:516 - Registration response from ***.***.net was OK

INFO 2018-09-10 17:15:49,090 Controller.py:521 - Resetting ActionQueue...

", None)

Connection to ***.***.net closed.

SSH command execution finished

host=***.***.net, exitcode=0

Command end time 2018-09-10 17:15:51

Registering with the server...

Registration with the server failed.

[#5]

Hello @jessica Chen!
Just to confirm, which language are you using in your OS?
I'm asking this cause I've spot this link:
https://community.hortonworks.com/questions/23409/there-is-a-problem-when-install-hdp-on-the-stepcon...

Hope this helps!

[#6]

HI @jessica Chen,

whats error message in when you perform the registration? doesn't see anything helpfull in the logs you shared.

you can do with this command as ambari-server side to see the Error logs.

tail -f  /var/log/ambari-server/ambari-server.log

[#7]

Here is what I got:

tail -f/var/log/ambari-server/ambari-server.log

INFO:root:Executing parallel bootstrap

INFO:root:Finished parallel bootstrap

11 Sep 2018 13:17:32,406INFO [pool-18-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/1

11 Sep 2018 13:17:32,407INFO [pool-18-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

11 Sep 2018 13:17:33,389INFO [pool-18-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/1

11 Sep 2018 13:17:33,389INFO [pool-18-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

11 Sep 2018 13:17:33,407INFO [pool-18-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/1

11 Sep 2018 13:17:33,407INFO [pool-18-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

11 Sep 2018 13:22:14,962INFO [pool-17-thread-1] AmbariMetricSinkImpl:278 - No live collector to send metrics to. Metrics to be sent will be discarded. This message will be skipped for the next 20 times.

11 Sep 2018 13:37:18,613INFO [ambari-client-thread-37] BootStrapImpl:108 - BootStrapping hosts ***01.**.***:***02.**.***:***03.**.***:

11 Sep 2018 13:37:18,615INFO [Thread-32] BSRunner:189 - Kicking off the scheduler for polling on logs in /var/run/ambari-server/bootstrap/2

11 Sep 2018 13:37:18,616INFO [pool-19-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/2

11 Sep 2018 13:37:18,617INFO [Thread-32] BSRunner:258 - Host= ***01.**.***,***02.**.***,***03.**.*** bs=/usr/lib/python2.6/site-packages/ambari_server/bootstrap.py requestDir=/var/run/ambari-server/bootstrap/2 user=root sshPort=22 keyfile=/var/run/ambari-server/bootstrap/2/sshKey passwordFile null server=***01.**.*** version=2.6.1.5 serverPort=8080 userRunAs=root timeout=300

11 Sep 2018 13:37:18,617INFO [pool-19-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

11 Sep 2018 13:37:18,623INFO [Thread-32] BSRunner:286 - Bootstrap output, log=/var/run/ambari-server/bootstrap/2/bootstrap.err /var/run/ambari-server/bootstrap/2/bootstrap.out at ***01.**.***

11 Sep 2018 13:37:28,618INFO [pool-19-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/2

11 Sep 2018 13:37:28,618INFO [pool-19-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

11 Sep 2018 13:37:30,902WARN [qtp-ambari-agent-47] SecurityFilter:103 - Request https://***01.**.***:8440/ca doesn't match any pattern.

11 Sep 2018 13:37:30,903WARN [qtp-ambari-agent-47] SecurityFilter:62 - This request is not allowed on this port: https://***01.**.***:8440/ca

11 Sep 2018 13:37:31,308INFO [qtp-ambari-agent-48] HeartBeatHandler:385 - agentOsType = redhat7

11 Sep 2018 13:37:31,392INFO [qtp-ambari-agent-48] HostImpl:334 - Received host registration, host=[hostname=***01,fqdn=***01.**.***,domain=***.***,architecture=x86_64,processorcount=4,physicalprocessorcount=4,osname=redhat,osversion=7.4,osfamily=redhat,memory=28801816,uptime_hours=2,mounts=(available=13212668,mountpoint=/,used=3554308,percent=22%,size=16766976,device=/dev/mapper/vg00-root,type=xfs)(available=14389996,mountpoint=/dev,used=0,percent=0%,size=14389996,device=devtmpfs,type=devtmpfs)(available=842240,mountpoint=/boot,used=196096,percent=19%,size=1038336,device=/dev/sda1,type=xfs)(available=3299920,mountpoint=/opt,used=359856,percent=10%,size=3659776,device=/dev/mapper/vg00-opt,type=xfs)(available=2629132,mountpoint=/var,used=3652084,percent=59%,size=6281216,device=/dev/mapper/vg00-var,type=xfs)(available=1493104,mountpoint=/opt/patrol,used=593808,percent=29%,size=2086912,device=/dev/mapper/vg00-patrol,type=xfs)(available=3984360,mountpoint=/opt/Tanium,used=199704,percent=5%,size=4184064,device=/dev/mapper/vg00-tanium,type=xfs)(available=4151136,mountpoint=/opt/maestro,used=32928,percent=1%,size=4184064,device=/dev/mapper/vg00-maestro,type=xfs)(available=1005408,mountpoint=/opt/ibm-ucd,used=32928,percent=4%,size=1038336,device=/dev/mapper/vg00-ibmucd,type=xfs)(available=4150944,mountpoint=/tmp,used=33120,percent=1%,size=4184064,device=/dev/mapper/vg00-tmp,type=xfs)(available=1808296,mountpoint=/opt/bmc/bladelogic,used=278616,percent=14%,size=2086912,device=/dev/mapper/vg00-bladelogic,type=xfs)(available=3101972,mountpoint=/home,used=33516,percent=2%,size=3135488,device=/dev/mapper/vg00-home,type=xfs)(available=47129936,mountpoint=/comp/archive,used=32944,percent=1%,size=47162880,device=/dev/mapper/vg01-comparchive,type=xfs)(available=36649296,mountpoint=/comp/logs,used=32944,percent=1%,size=36682240,device=/dev/mapper/vg01-complogs,type=xfs)(available=62850896,mountpoint=/comp/runtime,used=32944,percent=1%,size=62883840,device=/dev/mapper/vg01-compruntime,type=xfs)(available=178284900,mountpoint=/***/resource,used=17503980,percent=9%,size=206290920,device=/dev/sdb1,type=ext4)]

, registrationTime=1536687451308, agentVersion=2.6.1.5

11 Sep 2018 13:37:31,393INFO [qtp-ambari-agent-48] TopologyManager:637 - TopologyManager.onHostRegistered: Entering

11 Sep 2018 13:37:31,393INFO [qtp-ambari-agent-48] TopologyManager:639 - TopologyManager.onHostRegistered: host = ***01.**.*** is already associated with the cluster or is currently being processed

11 Sep 2018 13:37:31,403INFO [qtp-ambari-agent-48] HeartBeatHandler:464 - Recovery configuration set to RecoveryConfig{, type=AUTO_START, maxCount=6, windowInMinutes=60, retryGap=5, maxLifetimeCount=1024, components=, recoveryTimestamp=1536687451402}

11 Sep 2018 13:37:31,565WARN [qtp-ambari-agent-48] SecurityFilter:103 - Request https://***01.**.***:8440/ca doesn't match any pattern.

11 Sep 2018 13:37:31,565WARN [qtp-ambari-agent-48] SecurityFilter:62 - This request is not allowed on this port: https://***01.**.***:8440/ca

11 Sep 2018 13:37:31,575WARN [qtp-ambari-agent-48] SecurityFilter:103 - Request https://***01.**.***:8440/ca doesn't match any pattern.

11 Sep 2018 13:37:31,575WARN [qtp-ambari-agent-48] SecurityFilter:62 - This request is not allowed on this port: https://***01.**.***:8440/ca

11 Sep 2018 13:37:31,977INFO [qtp-ambari-agent-48] HeartBeatHandler:385 - agentOsType = redhat7

11 Sep 2018 13:37:31,978INFO [qtp-ambari-agent-46] HeartBeatHandler:385 - agentOsType = redhat7

11 Sep 2018 13:37:32,030INFO [qtp-ambari-agent-48] HostImpl:334 - Received host registration, host=[hostname=***03,fqdn=***03.**.***,domain=***.***,architecture=x86_64,processorcount=4,physicalprocessorcount=4,osname=redhat,osversion=7.4,osfamily=redhat,memory=28801816,uptime_hours=2,mounts=(available=13502536,mountpoint=/,used=3264440,percent=20%,size=16766976,device=/dev/mapper/vg00-root,type=xfs)(available=14389944,mountpoint=/dev,used=0,percent=0%,size=14389944,device=devtmpfs,type=devtmpfs)(available=842140,mountpoint=/boot,used=196196,percent=19%,size=1038336,device=/dev/sda1,type=xfs)(available=4151000,mountpoint=/tmp,used=33064,percent=1%,size=4184064,device=/dev/mapper/vg00-tmp,type=xfs)(available=3299936,mountpoint=/opt,used=359840,percent=10%,size=3659776,device=/dev/mapper/vg00-opt,type=xfs)(available=1493100,mountpoint=/opt/patrol,used=593812,percent=29%,size=2086912,device=/dev/mapper/vg00-patrol,type=xfs)(available=4151136,mountpoint=/opt/maestro,used=32928,percent=1%,size=4184064,device=/dev/mapper/vg00-maestro,type=xfs)(available=1005408,mountpoint=/opt/ibm-ucd,used=32928,percent=4%,size=1038336,device=/dev/mapper/vg00-ibmucd,type=xfs)(available=3469776,mountpoint=/var,used=2811440,percent=45%,size=6281216,device=/dev/mapper/vg00-var,type=xfs)(available=3984212,mountpoint=/opt/Tanium,used=199852,percent=5%,size=4184064,device=/dev/mapper/vg00-tanium,type=xfs)(available=1808284,mountpoint=/opt/bmc/bladelogic,used=278628,percent=14%,size=2086912,device=/dev/mapper/vg00-bladelogic,type=xfs)(available=3101892,mountpoint=/home,used=33596,percent=2%,size=3135488,device=/dev/mapper/vg00-home,type=xfs)(available=62850896,mountpoint=/comp/runtime,used=32944,percent=1%,size=62883840,device=/dev/mapper/vg01-compruntime,type=xfs)(available=36649296,mountpoint=/comp/logs,used=32944,percent=1%,size=36682240,device=/dev/mapper/vg01-complogs,type=xfs)(available=47129936,mountpoint=/comp/archive,used=32944,percent=1%,size=47162880,device=/dev/mapper/vg01-comparchive,type=xfs)(available=192109732,mountpoint=/***/resource,used=3679148,percent=2%,size=206290920,device=/dev/sdb1,type=ext4)]

, registrationTime=1536687451977, agentVersion=2.6.1.5

11 Sep 2018 13:37:32,030INFO [qtp-ambari-agent-48] TopologyManager:637 - TopologyManager.onHostRegistered: Entering

11 Sep 2018 13:37:32,030INFO [qtp-ambari-agent-48] TopologyManager:639 - TopologyManager.onHostRegistered: host = ***03.**.*** is already associated with the cluster or is currently being processed

11 Sep 2018 13:37:32,035INFO [qtp-ambari-agent-46] HostImpl:334 - Received host registration, host=[hostname=***02,fqdn=***02.**.***,domain=***.***,architecture=x86_64,processorcount=4,physicalprocessorcount=4,osname=redhat,osversion=7.4,osfamily=redhat,memory=28801816,uptime_hours=2,mounts=(available=13350860,mountpoint=/,used=3416116,percent=21%,size=16766976,device=/dev/mapper/vg00-root,type=xfs)(available=14389944,mountpoint=/dev,used=0,percent=0%,size=14389944,device=devtmpfs,type=devtmpfs)(available=842140,mountpoint=/boot,used=196196,percent=19%,size=1038336,device=/dev/sda1,type=xfs)(available=4335448,mountpoint=/var,used=1945768,percent=31%,size=6281216,device=/dev/mapper/vg00-var,type=xfs)(available=3299920,mountpoint=/opt,used=359856,percent=10%,size=3659776,device=/dev/mapper/vg00-opt,type=xfs)(available=3983916,mountpoint=/opt/Tanium,used=200148,percent=5%,size=4184064,device=/dev/mapper/vg00-tanium,type=xfs)(available=4151136,mountpoint=/opt/maestro,used=32928,percent=1%,size=4184064,device=/dev/mapper/vg00-maestro,type=xfs)(available=4151000,mountpoint=/tmp,used=33064,percent=1%,size=4184064,device=/dev/mapper/vg00-tmp,type=xfs)(available=1005408,mountpoint=/opt/ibm-ucd,used=32928,percent=4%,size=1038336,device=/dev/mapper/vg00-ibmucd,type=xfs)(available=3101984,mountpoint=/home,used=33504,percent=2%,size=3135488,device=/dev/mapper/vg00-home,type=xfs)(available=1808268,mountpoint=/opt/bmc/bladelogic,used=278644,percent=14%,size=2086912,device=/dev/mapper/vg00-bladelogic,type=xfs)(available=36649296,mountpoint=/comp/logs,used=32944,percent=1%,size=36682240,device=/dev/mapper/vg01-complogs,type=xfs)(available=62850896,mountpoint=/comp/runtime,used=32944,percent=1%,size=62883840,device=/dev/mapper/vg01-compruntime,type=xfs)(available=47129936,mountpoint=/comp/archive,used=32944,percent=1%,size=47162880,device=/dev/mapper/vg01-comparchive,type=xfs)(available=1496204,mountpoint=/opt/patrol,used=590708,percent=29%,size=2086912,device=/dev/mapper/vg00-patrol,type=xfs)(available=192198208,mountpoint=/***/resource,used=3590672,percent=2%,size=206290920,device=/dev/sdb1,type=ext4)]

, registrationTime=1536687451978, agentVersion=2.6.1.5

11 Sep 2018 13:37:32,035INFO [qtp-ambari-agent-46] TopologyManager:637 - TopologyManager.onHostRegistered: Entering

11 Sep 2018 13:37:32,035INFO [qtp-ambari-agent-46] TopologyManager:639 - TopologyManager.onHostRegistered: host = ***02.**.*** is already associated with the cluster or is currently being processed

11 Sep 2018 13:37:32,038INFO [qtp-ambari-agent-48] HeartBeatHandler:464 - Recovery configuration set to RecoveryConfig{, type=AUTO_START, maxCount=6, windowInMinutes=60, retryGap=5, maxLifetimeCount=1024, components=METRICS_COLLECTOR, recoveryTimestamp=1536687452037}

11 Sep 2018 13:37:32,041INFO [qtp-ambari-agent-46] HeartBeatHandler:464 - Recovery configuration set to RecoveryConfig{, type=AUTO_START, maxCount=6, windowInMinutes=60, retryGap=5, maxLifetimeCount=1024, components=, recoveryTimestamp=1536687452041}

11 Sep 2018 13:37:36,627INFO [Thread-32] BSRunner:310 - Script log Mesg

INFO:root:BootStrapping hosts ['***01.**.***',

'***02.**.***',

'***03.**.***'] using /usr/lib/python2.6/site-packages/ambari_server cluster primary OS: redhat7 with user 'root'with ssh Port '22' sshKey File /var/run/ambari-server/bootstrap/2/sshKey password File null using tmp dir /var/run/ambari-server/bootstrap/2 ambari: ***01.**.***; server_port: 8080; ambari version: 2.6.1.5; user_run_as: root

INFO:root:Executing parallel bootstrap

INFO:root:Finished parallel bootstrap

11 Sep 2018 13:37:36,627INFO [pool-19-thread-1] BSHostStatusCollector:55 - Request directory /var/run/ambari-server/bootstrap/2

11 Sep 2018 13:37:36,627INFO [pool-19-thread-1] BSHostStatusCollector:62 - HostList for polling on [***01.**.***, ***02.**.***, ***03.**.***]

[#8]

Hi @jessica Chen,

Can you refer to jay's comments below and see if it helps you

[#9]

@jessica Chen

As we see the message in your out put:

11 Sep 2018 13:37:30,902 WARN [qtp-ambari-agent-47] SecurityFilter:103 - Request https://***01.**.***:8440/ca doesn't match any pattern.
11 Sep 2018 13:37:30,903 WARN [qtp-ambari-agent-47] SecurityFilter:62 - This request is not allowed on this port: https://***01.**.***:8440/ca

.

Notice warning "ca doesn't match any pattern" this can happen if the FQDN is not setup properly on ambari server/agent hosts.

Above can happen if the Ambari Server FQDN is not correctly defined. I will suggest you to please check the following:

1. Open your ambari-agent.ini file and then check if it is pointing to the Ambari Serevr FQDN correctly?

# grep 'hostname =' /etc/ambari-agent/conf/ambari-agent.ini

2. Run the following command on Ambari Server host to check if it has correct FQDN setup?

# hostname -f

The Hostname must match in the above command outputs.

Also check if the "/etc/hosts" file is correct in agent and server host to resolved correct FQDN.

.

Additionally : Also check if you ahve already added the following section inside your "/etc/ambari-agent/conf/ambari-agent.ini" file of all ambai agents and then restarted the ambari agent?

[security]
force_https_protocol=PROTOCOL_TLSv1_2

.

Also please refer to the following article to know more about the above property : java python updates and ambari agent tls settings