5 Replies Latest reply on Aug 18, 2016 8:15 AM by Bill Robinson

    default TLS protocols as service initialization failed

    DC Kidson

      Hi there.

       

      We are having problems using "NSH  HERE" to target servers.

       

      On our apsserver running as NSH Proxy when starting we are seeing the following errors.

       

      [17 Aug 2016 14:02:52,959] [main] [INFO] [::] [] The cipher suites [TLS_RSA_WITH_AES_256_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA] have been enabled on port [9941]
      [17 Aug 2016 14:02:53,048] [main] [WARN] [::] [] Using the default TLS protocols as service initialization failed for this application server.

       

      has anybody seen this before and potentially point me  in a troubleshooting direction?

       

      We are on bsa 8.8 currently.

       

      Thanks

      DC

        • 1. Re: default TLS protocols as service initialization failed
          Bill Robinson

          so is the nsh proxy server starting ?  can you attach the entire appserver log for that instance that you posted the snippet above from ?

          what version of bsa is this ?

           

          what error do you get from 'nsh here' ?

          • 2. Re: default TLS protocols as service initialization failed
            DC Kidson

            Hi Bill

             

            Yes the appserver is starting.

             

             

            i noticed the 2 blasadmin settings early  in the logs and tried  setting them with one complaining its read only.

             

            bladmin:deployment>show AppServer ValidateClientIpAddress

            ValidateClientIpAddress:true

            bladmin:deployment>set AppServer ValidateClientIpAdress false

            ValidateClientIpAdress is not a valid set option. This could be because it is a read only option.

            bladmin:deployment>set AppServer ValidateRequestURL false

             

             

            APPSERVER LOGS

             

            [17 Aug 2016 14:02:31,051] [main] [INFO] [::] [] Starting Agent Service...

            [17 Aug 2016 14:02:31,057] [main] [INFO] [::] [] Agent Service started.

            [17 Aug 2016 14:02:31,171] [main] [INFO] [::] [] String ClientValidation Service...

            [17 Aug 2016 14:02:31,171] [main] [INFO] [::] [] The application server will validate the ipaddress of the client while servicing a request. To configure please use 'set AppServer ValidateClientIpAddress {true|false}' command of Bladelogic Administrative Utility

            [17 Aug 2016 14:02:31,171] [main] [INFO] [::] [] The application server will validate the URL used by the client to connect to the application server. To configure please use 'set AppServer ValidateRequestURL {true|false}' command of Bladelogic Administrative Utility

            [17 Aug 2016 14:02:31,171] [main] [INFO] [::] [] ClientValidation Service started.

            [17 Aug 2016 14:02:31,174] [main] [INFO] [::] [] Starting Configuration Service...

            [17 Aug 2016 14:02:31,187] [main] [INFO] [::] [] Configuration Service started.

            [17 Aug 2016 14:02:31,201] [main] [INFO] [::] [] Starting Content Importer Service...

            [17 Aug 2016 14:02:31,201] [main] [INFO] [::] [] Content Importer Service started.

            [17 Aug 2016 14:02:31,259] [main] [INFO] [::] [] Loading JNI libaries...

            [17 Aug 2016 14:02:31,269] [main] [INFO] [::] [] Loaded JNI library BlAsJni

            [17 Aug 2016 14:02:31,270] [main] [INFO] [::] [] Loaded JNI library XJNIService

            [17 Aug 2016 14:02:31,270] [main] [INFO] [::] [] Loaded JNI library Atrium Core

            [17 Aug 2016 14:02:31,270] [main] [INFO] [::] [] Initializing JNI Configuration...

            [17 Aug 2016 14:02:31,385] [main] [INFO] [::] [] Initialized JNI library BlAsJni

            [17 Aug 2016 14:02:31,391] [main] [INFO] [::] [] Initialized JNI library XJNIService

            [17 Aug 2016 14:02:31,391] [main] [INFO] [::] [] Initialized JNI library Atrium Core

            [17 Aug 2016 14:02:31,391] [main] [INFO] [::] [] JNI Configuration complete.

            [17 Aug 2016 14:02:31,396] [main] [INFO] [::] [] Job Infra Manager Service is starting

            [17 Aug 2016 14:02:31,410] [main] [INFO] [::] [] Starting LDAP Connection Service...

            [17 Aug 2016 14:02:31,410] [main] [INFO] [::] [] LDAP Connection Service started.

            [17 Aug 2016 14:02:31,429] [main] [INFO] [::] [] Starting Model Graph Service...

            [17 Aug 2016 14:02:32,750] [main] [INFO] [::] [] Model Graph Service started.

            [17 Aug 2016 14:02:32,794] [main] [INFO] [::] [] Starting ObjectExporter Service...

            [17 Aug 2016 14:02:32,794] [main] [INFO] [::] [] Verifying exporter classes...

            [17 Aug 2016 14:02:32,803] [main] [INFO] [::] [] Object exporters verified.

            [17 Aug 2016 14:02:32,804] [main] [INFO] [::] [] ObjectExporter Service started.

            [17 Aug 2016 14:02:32,849] [main] [INFO] [::] [] Starting ObjectImporter Service...

            [17 Aug 2016 14:02:32,849] [main] [INFO] [::] [] Verifying importer classes...

            [17 Aug 2016 14:02:32,872] [main] [INFO] [::] [] Object importers verified.

            [17 Aug 2016 14:02:32,873] [main] [INFO] [::] [] ObjectImporter Service started.

            [17 Aug 2016 14:02:32,880] [main] [INFO] [::] [] Starting PKI Service...

            [17 Aug 2016 14:02:32,892] [main] [INFO] [::] [] PKI Service started.

            [17 Aug 2016 14:02:32,898] [main] [INFO] [::] [] Starting LDAP Service...

            [17 Aug 2016 14:02:32,987] [main] [INFO] [::] [] LDAP service is not configured.

            [17 Aug 2016 14:02:32,988] [main] [INFO] [::] [] LDAP Service started.

            [17 Aug 2016 14:02:32,992] [main] [INFO] [::] [] Starting Port Provider...

            [17 Aug 2016 14:02:32,992] [main] [INFO] [::] [] Port Provider started.

            [17 Aug 2016 14:02:33,014] [main] [INFO] [::] [] Starting Sizing Service...

            [17 Aug 2016 14:02:33,014] [main] [INFO] [::] [] Sizing Service started.

            [17 Aug 2016 14:02:33,016] [main] [INFO] [::] [] Starting Caching Service...

            [17 Aug 2016 14:02:33,016] [main] [INFO] [::] [] Caching Service started.

            [17 Aug 2016 14:02:33,018] [main] [INFO] [::] [] Starting Session Service...

            [17 Aug 2016 14:02:33,025] [main] [INFO] [::] [] Session Service started.

            [17 Aug 2016 14:02:33,027] [main] [INFO] [::] [] Starting JCIFS Connection Service...

            [17 Aug 2016 14:02:33,028] [main] [INFO] [::] [] JCIFS Connection Service started.

            [17 Aug 2016 14:02:33,044] [main] [INFO] [::] [] Starting Task Scheduling Service...

            [17 Aug 2016 14:02:33,045] [main] [INFO] [::] [] Task Scheduling Service started.

            [17 Aug 2016 14:02:33,047] [main] [INFO] [::] [] Starting Directory Service...

            [17 Aug 2016 14:02:33,048] [main] [INFO] [::] [] Reserving 9950 as the port for RMICommunicationPort

            [17 Aug 2016 14:02:33,054] [main] [INFO] [::] [] Starting RMI registry on host: APPSERVER

            [17 Aug 2016 14:02:33,069] [main] [INFO] [::] [] Directory Service started.

            [17 Aug 2016 14:02:33,073] [main] [INFO] [::] [] Setting authenticator: com.bladelogic.om.infra.app.service.manage.auth.BlSessionServerAuthenticator

            [17 Aug 2016 14:02:33,073] [main] [INFO] [::] [] Setting server socket factory: com.bladelogic.om.infra.app.service.client.ServerSocketFactory

            [17 Aug 2016 14:02:33,073] [main] [INFO] [::] [] Setting client socket factory: com.bladelogic.om.infra.app.service.client.OMClientSocketFactory

            [17 Aug 2016 14:02:33,074] [main] [INFO] [::] [] Starting Management Service ...

            [17 Aug 2016 14:02:33,165] [main] [INFO] [::] [] Management Service started.

            [17 Aug 2016 14:02:33,168] [main] [INFO] [::] [] Starting Instrumentation Service...

            [17 Aug 2016 14:02:33,172] [main] [INFO] [::] [] Instrumentation Service started.

            [17 Aug 2016 14:02:33,172] [main] [INFO] [::] [] Starting App Server Data Source...

            [17 Aug 2016 14:02:33,718] [main] [INFO] [::] [] Initialized connection pool General-Connection-Pool with url = jdbc:oracle:thin:@10.25.216.24:1521:BSA86 and user = BLADELOGIC

            [17 Aug 2016 14:02:33,875] [main] [INFO] [::] [] Initialized connection pool Job-Connection-Pool with url = jdbc:oracle:thin:@10.25.216.24:1521:BSA86 and user = BLADELOGIC

            [17 Aug 2016 14:02:34,025] [main] [INFO] [::] [] Initialized connection pool Client-Connection-Pool with url = jdbc:oracle:thin:@10.25.216.24:1521:BSA86 and user = BLADELOGIC

            [17 Aug 2016 14:02:34,026] [main] [INFO] [::] [] App Server Data Source started.

            [17 Aug 2016 14:02:34,027] [main] [INFO] [::] [] Don't proceed with Oracle RAC support initialization. The environment variable ORACLE_HOME is not defined.

            [17 Aug 2016 14:02:34,121] [main] [INFO] [::] [] Starting Database Service...

            [17 Aug 2016 14:02:34,179] [main] [INFO] [::] [] Loaded /opt/bmc/bladelogic/NSH/br/deployments/APPSERVER/sql/sqlmap.properties

            [17 Aug 2016 14:02:34,234] [main] [INFO] [::] [] Loaded /opt/bmc/bladelogic/NSH/br/deployments/APPSERVER/sql/streamable_sqlmap.properties

            [17 Aug 2016 14:02:34,249] [main] [INFO] [::] [] Database service loaded 2 mapping files.

            [17 Aug 2016 14:02:34,250] [main] [INFO] [::] [] Database Service started.

            [17 Aug 2016 14:02:34,306] [main] [INFO] [::] [] Starting App Server Service...

            [17 Aug 2016 14:02:34,501] [main] [INFO] [::] [] Server: APPSERVER; id: 2000200; RMI Port: 9836

            [17 Aug 2016 14:02:34,610] [main] [INFO] [::] [] App Server Service started.

            [17 Aug 2016 14:02:34,610] [main] [INFO] [::] [] Starting Atrium Import Service...

            [17 Aug 2016 14:02:34,610] [main] [INFO] [::] [] Atrium Import Service started.

            [17 Aug 2016 14:02:34,611] [main] [INFO] [::] [] Starting Object Deleter Service...

            [17 Aug 2016 14:02:34,611] [main] [INFO] [::] [] Verifying object deleters...

            [17 Aug 2016 14:02:34,615] [main] [INFO] [::] [] Object deleters verified.

            [17 Aug 2016 14:02:34,615] [main] [INFO] [::] [] Verifying object soft deleters...

            [17 Aug 2016 14:02:34,628] [main] [INFO] [::] [] Object soft deleters verified.

            [17 Aug 2016 14:02:34,629] [main] [INFO] [::] [] Object Deleter Service started

            [17 Aug 2016 14:02:34,629] [main] [INFO] [::] [] Starting Notification Service...

            [17 Aug 2016 14:02:34,631] [main] [INFO] [::] [] Notification Service started.

            [17 Aug 2016 14:02:34,631] [main] [INFO] [::] [] Starting SRP Authentication Service...

            [17 Aug 2016 14:02:34,640] [main] [INFO] [::] [] SRP Authentication Service started.

            [17 Aug 2016 14:02:34,653] [main] [INFO] [::] [] Starting Event Service...

            [17 Aug 2016 14:02:34,663] [Event-Processor-Thread-0] [INFO] [::] [] Event processor thread started.

            [17 Aug 2016 14:02:34,665] [Event-Processor-Thread-1] [INFO] [::] [] Event processor thread started.

            [17 Aug 2016 14:02:34,666] [Event-Processor-Thread-2] [INFO] [::] [] Event processor thread started.

            [17 Aug 2016 14:02:34,663] [Event-Processor-Thread-3] [INFO] [::] [] Event processor thread started.

            [17 Aug 2016 14:02:34,667] [Event-Transfer-Thread-0] [INFO] [::] [] Event transfer thread started.

            [17 Aug 2016 14:02:34,668] [Event-Transfer-Thread-3] [INFO] [::] [] Event transfer thread started.

            [17 Aug 2016 14:02:34,668] [Event-Transfer-Thread-2] [INFO] [::] [] Event transfer thread started.

            [17 Aug 2016 14:02:34,667] [Event-Transfer-Thread-1] [INFO] [::] [] Event transfer thread started.

            [17 Aug 2016 14:02:34,672] [Event-Transfer-Thread-4] [INFO] [::] [] Event transfer thread started.

            [17 Aug 2016 14:02:34,674] [main] [INFO] [::] [] Event Service started.

            [17 Aug 2016 14:02:34,674] [main] [INFO] [::] [] Starting RBAC User Service...

            [17 Aug 2016 14:02:34,674] [main] [INFO] [::] [] RBAC User Service started.

            [17 Aug 2016 14:02:34,674] [main] [INFO] [::] [] Starting Diagnostic Service...

            [17 Aug 2016 14:02:34,675] [Event-Dispatcher-Thread] [INFO] [::] [] Event dispatcher thread started.

            [17 Aug 2016 14:02:34,676] [Event-Processor-Thread-4] [INFO] [::] [] Event processor thread started.

            [17 Aug 2016 14:02:34,679] [main] [INFO] [::] [] Diagnostic Service started.

            [17 Aug 2016 14:02:34,680] [main] [INFO] [::] [] Starting NshProxyConnection Service...

            [17 Aug 2016 14:02:34,680] [main] [INFO] [::] [] Nsh-Proxy is starting worker threads

            [17 Aug 2016 14:02:34,682] [Diagnostic-Test-Runner-Thread-1] [INFO] [::] [] Diagnostic test execution thread started.

            [17 Aug 2016 14:02:34,682] [Diagnostic-Test-Runner-Thread-0] [INFO] [::] [] Diagnostic test execution thread started.

            [17 Aug 2016 14:02:34,682] [Diagnostic-Test-Runner-Thread-2] [INFO] [::] [] Diagnostic test execution thread started.

            [17 Aug 2016 14:02:34,682] [Nsh-Proxy-Thread-0] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,683] [Nsh-Proxy-Thread-1] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,683] [Nsh-Proxy-Thread-2] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,683] [Nsh-Proxy-Thread-3] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,684] [Nsh-Proxy-Thread-4] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,684] [Nsh-Proxy-Thread-5] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,685] [Nsh-Proxy-Thread-6] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,685] [Nsh-Proxy-Thread-7] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,685] [Nsh-Proxy-Thread-8] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,686] [Nsh-Proxy-Thread-9] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,686] [Nsh-Proxy-Thread-10] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,686] [Nsh-Proxy-Thread-11] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,687] [Nsh-Proxy-Thread-12] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,687] [Nsh-Proxy-Thread-13] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,687] [main] [INFO] [::] [] Nsh-Proxy is reserving the controlport

            [17 Aug 2016 14:02:34,687] [main] [INFO] [::] [] Reserving 9951 as the port for Nsh-Proxy Control Port

            [17 Aug 2016 14:02:34,688] [Nsh-Proxy-Thread-14] [INFO] [::] [] Nsh proxy thread started

            [17 Aug 2016 14:02:34,688] [main] [INFO] [::] [] Nsh-Proxy is establishing control connection...

            [17 Aug 2016 14:02:34,689] [Thread-13] [INFO] [::] [] Nsh-Proxy is waiting for control socket ...

            [17 Aug 2016 14:02:34,689] [Thread-13] [INFO] [::] [] Nsh-Proxy accepted the control socket connection

            [17 Aug 2016 14:02:34,689] [main] [INFO] [::] [] Nsh-Proxy established control connection.

            [17 Aug 2016 14:02:34,697] [main] [INFO] [::] [] Nsh-Proxy is starting the selector

            [17 Aug 2016 14:02:34,715] [main] [INFO] [::] [] Nsh-Proxy is starting the acceptor

            [17 Aug 2016 14:02:34,717] [main] [INFO] [::] [] NshProxyConnection Service started.

            [17 Aug 2016 14:02:34,717] [main] [INFO] [::] [] Starting SSH Connection Service...

            [17 Aug 2016 14:02:34,717] [main] [INFO] [::] [] SSH Connection Service started.

            [17 Aug 2016 14:02:34,717] [main] [INFO] [::] [] Starting RBAC Role Service...

            [17 Aug 2016 14:02:34,717] [main] [INFO] [::] [] RBAC Role Service started.

            [17 Aug 2016 14:02:34,722] [main] [INFO] [::] [] Adding method invocation provider: com.bladelogic.om.infra.app.api.manage.APIMethodInvocationProvider

            [17 Aug 2016 14:02:34,722] [main] [INFO] [::] [] Adding method invocation provider: com.bladelogic.om.infra.app.service.agentservice.AgentMethodInvocationProvider

            [17 Aug 2016 14:02:34,724] [main] [INFO] [::] [] String BlExecService...

            [17 Aug 2016 14:02:34,729] [Bl-Exec-0] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,729] [Bl-Exec-1] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,731] [Bl-Exec-2] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,734] [Bl-Exec-3] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,734] [Bl-Exec-4] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,734] [Bl-Exec-5] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,735] [Bl-Exec-6] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,735] [Bl-Exec-7] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,736] [Bl-Exec-8] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,736] [Bl-Exec-9] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,736] [Bl-Exec-10] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,737] [Bl-Exec-11] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,737] [Bl-Exec-12] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,737] [Bl-Exec-13] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,738] [Bl-Exec-14] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,738] [Bl-Exec-15] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,738] [Bl-Exec-16] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,739] [Bl-Exec-17] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,739] [Bl-Exec-18] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,740] [Bl-Exec-19] [INFO] [::] [] BlExec worker thread started.

            [17 Aug 2016 14:02:34,741] [main] [INFO] [::] [] BlExec Service started.

            [17 Aug 2016 14:02:34,741] [main] [INFO] [::] [] Starting RBAC Service...

            [17 Aug 2016 14:02:34,741] [main] [INFO] [::] [] Registering Security checkers...

            [17 Aug 2016 14:02:34,850] [main] [INFO] [::] [] Security checkers registered.

            [17 Aug 2016 14:02:34,850] [main] [INFO] [::] [] RBAC Service started.

            [17 Aug 2016 14:02:34,850] [main] [INFO] [::] [] Starting Job Run Service...

            [17 Aug 2016 14:02:35,132] [main] [INFO] [::] [] Job Run Service started.

            [17 Aug 2016 14:02:35,133] [main] [INFO] [::] [] Starting Job Schedule Approval Service...

            [17 Aug 2016 14:02:35,140] [Approval-0] [INFO] [::] [] Approval processor thread started.

            [17 Aug 2016 14:02:35,140] [Approval-1] [INFO] [::] [] Approval processor thread started.

            [17 Aug 2016 14:02:35,140] [main] [INFO] [::] [] Job Schedule Approval Service started

            [17 Aug 2016 14:02:35,140] [Approval-2] [INFO] [::] [] Approval processor thread started.

            [17 Aug 2016 14:02:35,140] [main] [INFO] [::] [] Starting Routing Service...

            [17 Aug 2016 14:02:37,318] [main] [INFO] [::] [] Routing Service started.

            [17 Aug 2016 14:02:37,318] [main] [INFO] [::] [] Starting Schedule Service...

            [17 Aug 2016 14:02:37,318] [main] [INFO] [::] [] Schedule Service started.

            [17 Aug 2016 14:02:37,320] [main] [INFO] [::] [] Starting RBAC Authentication User Service...

            [17 Aug 2016 14:02:37,320] [main] [INFO] [::] [] RBAC Authentication User Service started.

            [17 Aug 2016 14:02:37,320] [main] [INFO] [::] [] Starting File Manager Service...

            [17 Aug 2016 14:02:37,333] [main] [INFO] [::] [] Validating file server host (fileSERVER.ccdc.local)...

            [17 Aug 2016 14:02:37,494] [main] [INFO] [::] [] Validating file server path (//fileSERVER.ccdc.local/data/BMC/BBSA/storage/)...

            [17 Aug 2016 14:02:37,496] [main] [INFO] [::] [] Cleaning up application server temp directory: /opt/bmc/bladelogic/NSH/tmp/APPSERVER

            [17 Aug 2016 14:02:37,500] [main] [INFO] [::] [] Cleaning up file server temp directory: //fileSERVER.ccdc.local/data/BMC/BBSA/storage/tmp/APPSERVER

            [17 Aug 2016 14:02:37,506] [main] [INFO] [::] [] Cleaning up temp zip files in: /opt/bmc/bladelogic/NSH/br

            [17 Aug 2016 14:02:37,514] [main] [INFO] [::] [] File Manager Service started.

            [17 Aug 2016 14:02:37,641] [main] [INFO] [::] [] Starting Cleanup Service...

            [17 Aug 2016 14:02:52,007] [main] [INFO] [::] [] Cleanup Service started.

            [17 Aug 2016 14:02:52,015] [main] [INFO] [::] [] Starting Authentication Service...

            [17 Aug 2016 14:02:52,204] [main] [INFO] [::] [] Authentication-Service is starting worker threads

            [17 Aug 2016 14:02:52,207] [Authentication-Service-Thread-0] [INFO] [::] [] Authentication Service thread started

            [17 Aug 2016 14:02:52,208] [Authentication-Service-Thread-1] [INFO] [::] [] Authentication Service thread started

            [17 Aug 2016 14:02:52,216] [Authentication-Service-Thread-2] [INFO] [::] [] Authentication Service thread started

            [17 Aug 2016 14:02:52,218] [Authentication-Service-Thread-3] [INFO] [::] [] Authentication Service thread started

            [17 Aug 2016 14:02:52,222] [main] [INFO] [::] [] Authentication Service started.

            [17 Aug 2016 14:02:52,222] [main] [INFO] [::] [] Starting Support Service...

            [17 Aug 2016 14:02:52,226] [Authentication-Service-Thread-4] [INFO] [::] [] Authentication Service thread started

            [17 Aug 2016 14:02:52,226] [Support-Thread-0] [INFO] [::] [] Support data thread started.

            [17 Aug 2016 14:02:52,227] [Support-Thread-1] [INFO] [::] [] Support data thread started.

            [17 Aug 2016 14:02:52,228] [main] [INFO] [::] [] Support Service started.

            [17 Aug 2016 14:02:52,228] [main] [INFO] [::] [] Starting Grammar Service...

            [17 Aug 2016 14:02:52,228] [main] [INFO] [::] [] Upgrading custom grammars...

            [17 Aug 2016 14:02:52,234] [Support-Thread-2] [INFO] [::] [] Support data thread started.

            [17 Aug 2016 14:02:52,253] [main] [INFO] [::] [] Found custom grammar complianceini.gm with location

            [17 Aug 2016 14:02:52,253] [main] [INFO] [::] [] Found custom grammar kexml.gm with location

            [17 Aug 2016 14:02:52,253] [main] [INFO] [::] [] Found custom grammar cc_cfg.gm with location

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Found custom grammar cc_nsvp.gm with location

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Deleted cached grammar file: /opt/bmc/bladelogic/NSH/scripts/cc_nsvp.gm

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Deleted cached grammar file: /opt/bmc/bladelogic/NSH/scripts/cc_cfg.gm

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Deleted cached grammar file: /opt/bmc/bladelogic/NSH/scripts/complianceini.gm

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Deleted cached grammar file: /opt/bmc/bladelogic/NSH/scripts/kexml.gm

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Custom grammars upgraded.

            [17 Aug 2016 14:02:52,254] [main] [INFO] [::] [] Grammar Service started.

            [17 Aug 2016 14:02:52,255] [main] [INFO] [::] [] Starting ClientConnection Service...

            [17 Aug 2016 14:02:52,419] [main] [INFO] [::] [] Client-Connections is starting the worker threads

            [17 Aug 2016 14:02:52,422] [Client-Connections-Thread-0] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,423] [Client-Connections-Thread-1] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,428] [Client-Connections-Thread-2] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,428] [Client-Connections-Thread-3] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,429] [Client-Connections-Thread-4] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,430] [Client-Connections-Thread-5] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,431] [Client-Connections-Thread-6] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,431] [Client-Connections-Thread-7] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,432] [Client-Connections-Thread-8] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,432] [main] [INFO] [::] [] ClientConnection Service started.

            [17 Aug 2016 14:02:52,439] [main] [INFO] [::] [] Starting Service Lifecycle Service...

            [17 Aug 2016 14:02:52,439] [main] [INFO] [::] [] Service Lifecycle Service started.

            [17 Aug 2016 14:02:52,439] [main] [INFO] [::] [] Registering remote services...

            [17 Aug 2016 14:02:52,446] [Client-Connections-Thread-9] [INFO] [::] [] Client worker thread started.

            [17 Aug 2016 14:02:52,589] [main] [INFO] [::] [] Remote services registered.

            [17 Aug 2016 14:02:52,701] [main] [INFO] [::] [] Registering mbean services...

            [17 Aug 2016 14:02:52,757] [main] [INFO] [::] [] MBean services registered.

            [17 Aug 2016 14:02:52,757] [main] [INFO] [::] [] Performing post-deployment operations...

            [17 Aug 2016 14:02:52,757] [main] [INFO] [::] [] Authentication-Service is reserving the controlport

            [17 Aug 2016 14:02:52,757] [main] [INFO] [::] [] Reserving 9952 as the port for Authentication-Service Control Port

            [17 Aug 2016 14:02:52,760] [main] [INFO] [::] [] Authentication-Service is establishing control connection...

            [17 Aug 2016 14:02:52,761] [Thread-16] [INFO] [::] [] Authentication-Service is waiting for control socket ...

            [17 Aug 2016 14:02:52,761] [Thread-16] [INFO] [::] [] Authentication-Service accepted the control socket connection

            [17 Aug 2016 14:02:52,762] [main] [INFO] [::] [] Authentication-Service established control connection.

            [17 Aug 2016 14:02:52,859] [main] [INFO] [::] [] The cipher suites [TLS_RSA_WITH_AES_256_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA] have been enabled on port [9940]

            [17 Aug 2016 14:02:52,950] [main] [WARN] [::] [] Using the default TLS protocols as service initialization failed for this application server

            [17 Aug 2016 14:02:52,951] [main] [INFO] [::] [] The TLS protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] have been enabled on port [9940]

            [17 Aug 2016 14:02:52,951] [main] [INFO] [::] [] Authentication-Service is starting the selector

            [17 Aug 2016 14:02:52,951] [main] [INFO] [::] [] Authentication-Service is starting the acceptor

            [17 Aug 2016 14:02:52,951] [main] [INFO] [::] [] Client-Connections is reserving the controlport

            [17 Aug 2016 14:02:52,952] [main] [INFO] [::] [] Reserving 9953 as the port for Client-Connections Control Port

            [17 Aug 2016 14:02:52,953] [main] [INFO] [::] [] Client-Connections is establishing control connection...

            [17 Aug 2016 14:02:52,954] [Thread-17] [INFO] [::] [] Client-Connections is waiting for control socket ...

            [17 Aug 2016 14:02:52,954] [Thread-17] [INFO] [::] [] Client-Connections accepted the control socket connection

            [17 Aug 2016 14:02:52,954] [main] [INFO] [::] [] Client-Connections established control connection.

            [17 Aug 2016 14:02:52,959] [main] [INFO] [::] [] The cipher suites [TLS_RSA_WITH_AES_256_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA] have been enabled on port [9941]

            [17 Aug 2016 14:02:53,048] [main] [WARN] [::] [] Using the default TLS protocols as service initialization failed for this application server

            [17 Aug 2016 14:02:53,048] [main] [INFO] [::] [] The TLS protocols [SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] have been enabled on port [9941]

            [17 Aug 2016 14:02:53,048] [main] [INFO] [::] [] Client-Connections is starting the selector

            [17 Aug 2016 14:02:53,049] [main] [INFO] [::] [] Client-Connections is starting the acceptor

            [17 Aug 2016 14:02:53,049] [main] [INFO] [::] [] Starting Tomcat Service ...

            [17 Aug 2016 14:02:53,247] [main] [INFO] [::] [] The cipher suites [TLS_DHE_RSA_WITH_AES_256_CBC_SHA,SSL_DHE_RSA_WITH_3DES_EDE_CBC_SHA,TLS_RSA_WITH_AES_256_CBC_SHA,SSL_RSA_WITH_3DES_EDE_CBC_SHA] have been enabled on port [9943]

            [17 Aug 2016 14:02:53,334] [main] [WARN] [::] [] Using the default TLS protocols as service initialization failed for this application server

            [17 Aug 2016 14:02:54,528] [localhost-startStop-1] [INFO] [::] [] WSAuthenticationModule.init

            [17 Aug 2016 14:02:55,090] [main] [WARN] [::] [] Registry server not configured.  Web Services not published.

            [17 Aug 2016 14:02:55,091] [main] [INFO] [::] [] Post Deploy Service Lifecycle Service...

            [17 Aug 2016 14:02:55,091] [main] [INFO] [::] [] Post Deploy Service Lifecycle Service complete.

            [17 Aug 2016 14:02:55,091] [main] [INFO] [::] [] Post-deployment operations completed.

            [17 Aug 2016 14:02:55,103] [main] [INFO] [::] [] BLManager Version = 8.8.00.338

            File server root location = //fileSERVER.ccdc.local/data/BMC/BBSA/storage/

            BLAST usage = Level 1 Enabled

            PropertySync = Disabled

             

             

             

             

            [17 Aug 2016 14:02:55,105] [main] [INFO] [::] [] Hostname = APPSERVER

            OS Name = Linux

            Version = 3.10.0-229.14.1.el7.x86_64

            Architecture = amd64

            Number of Processors Available to JVM = 2

            JRE Version = 1.8.0_71 from Oracle Corporation

            JRE Data Model = 64-bit

            JRE VM Name = Java HotSpot(TM) 64-Bit Server VM

            Maximum JVM Memory (B) = 3,817,865,216

            Total JVM Memory (B) = 707,264,512

            Free JVM Memory (B) = 501,013,024

            Used JVM Memory (B) = 206,251,488

            Maximum File Descriptors = 8,192

            Used File Descriptors = 300

            FIPS 140-2 Mode = enabled

            Total OS Memory (B) = 12,431,900,672

            Available OS Memory (B) = 907,243,520

             

             

            BLManager Current Time = Wed Aug 17 14:02:55 BST 2016

            BLManager Started = Wed Aug 17 14:02:34 BST 2016

            BLManager PID = 7831

             

             

             

             

            [17 Aug 2016 14:02:55,105] [main] [INFO] [::] [] Accepting requests...

            [17 Aug 2016 14:02:55,105] [main] [INFO] [::] [] Ready.

            [17 Aug 2016 14:02:55,105] [main] [INFO] [::] [] Performing post-startup deployment operations...

            [17 Aug 2016 14:02:55,109] [main] [INFO] [::] [] Importing content...

            [17 Aug 2016 14:02:55,110] [main] [INFO] [::] [] Importing content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/puppetscripts...

            [17 Aug 2016 14:02:55,111] [main] [INFO] [::] [] Imported content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/puppetscripts

            [17 Aug 2016 14:02:55,114] [main] [INFO] [::] [] Importing content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/nshscripts...

            [17 Aug 2016 14:02:55,114] [main] [INFO] [::] [] Imported content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/nshscripts

            [17 Aug 2016 14:02:55,115] [main] [INFO] [::] [] Importing content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/chefscripts...

            [17 Aug 2016 14:02:55,115] [main] [INFO] [::] [] Imported content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/chefscripts

            [17 Aug 2016 14:02:55,115] [main] [INFO] [::] [] Importing content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/assetclasslibraries...

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Imported content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/assetclasslibraries

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Importing content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/customobjects...

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Imported content from directory /opt/bmc/bladelogic/NSH/br/autoimportcontent/customobjects

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Content imported.

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Initializing Advanced File Server support...

            [17 Aug 2016 14:02:55,116] [main] [INFO] [::] [] Advanced File Server support initialized.

            [17 Aug 2016 14:02:55,122] [main] [INFO] [::] [] Initializing Remote Host Authentications...

            [17 Aug 2016 14:02:55,132] [main] [INFO] [::] [] Remote Host Authentications Initialized.

            [17 Aug 2016 14:02:55,133] [main] [INFO] [::] [] Post Startup Service Lifecycle Service...

            [17 Aug 2016 14:02:55,133] [main] [INFO] [::] [] Requesting Asset Class cache priming

            • 3. Re: default TLS protocols as service initialization failed
              Bill Robinson

              so what error are you getting from nsh when you do 'nsh here' ?

              • 4. Re: default TLS protocols as service initialization failed
                DC Kidson

                HI Bill.

                 

                We get a remote host "unknown".

                 

                We have distributed deployment with targets sitting behind a socks server.

                 

                Only the servers behind the socks seems to be affected as i can nsh to any of the servers residing in the immediate network

                This is something that used to work ,but since a recent upgrade we have lost this capability.

                 

                I noticed the following discussion and it might be the same issue we are facing

                NSH Here failing to connect to servers behind Socks Proxy

                 

                Both blasadmin url and ip validations has also been disabled with no affect.

                 

                thanks

                DC

                • 5. Re: default TLS protocols as service initialization failed
                  Bill Robinson

                  i'm not sure how you are connecting a remote host unknown error - which indicates a name resolution issue w/ appserver configuration settings.

                   

                  so the nsh client cannot resolve the target system ?  and was this working under a previous version of bsa and not working after upgrading to 8.8 or this is a clean 8.8 install ?