DATAGO-79228: Update Solace Spring Boot version #460
GitHub Actions / Test Results
succeeded
Jul 18, 2024 in 0s
2 fail, 1 skipped, 1 118 pass in 20m 21s
Annotations
github-actions / Test Results
checkHealthOfMultipleSolaceBindersWhenForceReconnect{MockMvc} (com.solace.spring.cloud.stream.binder.springBootTests.oauth2.MultiBinderOAuth2IT) failed
solace-spring-cloud-stream-binder/solace-spring-cloud-stream-binder/target/failsafe-reports/TEST-com.solace.spring.cloud.stream.binder.springBootTests.oauth2.MultiBinderOAuth2IT.xml [took 1m 40s]
Raw output
Multiple Exceptions (2):
Status expected:<200> but was:<503>
JSON path "components.binders.components.solace2.status" expected:<UP> but was:<DOWN>
java.lang.AssertionError:
Multiple Exceptions (2):
Status expected:<200> but was:<503>
JSON path "components.binders.components.solace2.status" expected:<UP> but was:<DOWN>
at com.solace.spring.cloud.stream.binder.springBootTests.oauth2.MultiBinderOAuth2IT.checkHealthOfMultipleSolaceBindersWhenForceReconnect(MultiBinderOAuth2IT.java:199)
2024-07-18 20:38:01,322 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012200fb/cmoLRifcY9
2024-07-18 20:38:01,359 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 48356 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 251)
2024-07-18 20:38:01,359 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: handleException(): Channel Closed Event (smfclient 251) with exception: null cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:01,359 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 48356 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 251); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:01,360 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:38:01,360 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:38:01,365 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:38:01,365 INFO Context_2_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace1-oauth2-client
2024-07-18 20:38:01,435 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:38:01,436 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 251, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:01,437 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:01,499 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 251)
2024-07-18 20:38:01,501 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:38:01,501 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:01,501 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:38:01,546 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:38:01,546 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:11,421 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012500fc/n4w6YP5h4j
2024-07-18 20:38:11,440 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 48360 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 252)
2024-07-18 20:38:11,440 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: handleException(): Channel Closed Event (smfclient 252) with exception: null cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:11,441 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 48360 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 252); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:11,441 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:38:11,441 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:38:11,441 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:38:11,441 INFO Context_3_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace2-oauth2-client
2024-07-18 20:38:11,506 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:38:11,507 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 252, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:38:11,507 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:38:11,507 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:38:11,507 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:11,507 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:11,508 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:38:11,508 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:38:11,508 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:11,508 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:11,563 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 252)
2024-07-18 20:38:11,564 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:38:11,564 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:11,564 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:38:11,609 DEBUG Context_3_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:38:11,609 DEBUG Context_3_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:21,502 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012200fb/cmoLRifcY9
2024-07-18 20:38:21,521 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 42084 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 251)
2024-07-18 20:38:21,521 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: handleException(): Channel Closed Event (smfclient 251) with exception: null cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:21,521 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 42084 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 251); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:21,521 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:38:21,521 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:38:21,521 INFO Context_2_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace1-oauth2-client
2024-07-18 20:38:21,521 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:38:21,521 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:38:21,586 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:38:21,587 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 251, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:38:21,588 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:21,589 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:38:21,642 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 251)
2024-07-18 20:38:21,644 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:38:21,644 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:21,644 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:38:21,685 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:38:21,685 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:38:31,586 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012500fc/n4w6YP5h4j
2024-07-18 20:38:31,606 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 51244 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 252)
2024-07-18 20:38:31,606 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: handleException(): Channel Closed Event (smfclient 252) with exception: null cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:31,607 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 51244 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 252); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:38:31,607 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:38:31,607 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:38:31,607 INFO Context_3_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace2-oauth2-client
2024-07-18 20:38:31,607 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:38:31,607 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:38:31,680 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:38:31,680 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 252, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:38:31,681 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_…d=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:01,847 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 44324 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 251); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:01,848 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:01,848 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:39:01,848 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:39:01,848 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:39:01,849 INFO Context_2_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace1-oauth2-client
2024-07-18 20:39:01,909 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:39:01,909 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 251, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:01,910 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:01,962 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 251)
2024-07-18 20:39:01,964 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:39:01,964 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:01,964 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:02,005 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:39:02,005 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:11,908 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012500fc/n4w6YP5h4j
2024-07-18 20:39:11,925 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 39542 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 252)
2024-07-18 20:39:11,926 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: handleException(): Channel Closed Event (smfclient 252) with exception: null cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:11,926 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 39542 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 252); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:11,926 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:11,926 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:39:11,926 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:39:11,926 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:39:11,927 INFO Context_3_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace2-oauth2-client
2024-07-18 20:39:11,986 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:39:11,986 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 252, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:39:11,987 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:39:11,987 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:39:11,987 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:11,988 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:11,988 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:39:11,988 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:39:11,988 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:11,988 INFO Context_3_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:12,042 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 252)
2024-07-18 20:39:12,044 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:39:12,044 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:12,044 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:12,085 DEBUG Context_3_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:39:12,085 DEBUG Context_3_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:21,987 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012200fb/cmoLRifcY9
2024-07-18 20:39:22,013 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 57844 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 251)
2024-07-18 20:39:22,014 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: handleException(): Channel Closed Event (smfclient 251) with exception: null cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:22,014 INFO Context_2_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-251: (Client name: fv-az1208-52/6734/012200fb/cmoLRifcY9 Local port: 57844 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 251); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_2_ReactorThread" daemon id=290, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:22,014 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:39:22,014 INFO Context_2_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace1-oauth2-client
2024-07-18 20:39:22,014 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:22,014 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:39:22,014 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:39:22,080 DEBUG Context_2_Thread_reconnect_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is RECONNECTING
2024-07-18 20:39:22,080 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connecting to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (host 1 of 1, smfclient 251, attempt 1 of 2147483647, this_host_attempt: 1 of 1)
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SNIUtil:92 - Server Name Indication (SNI) automatically applied by using provided hostname
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:739 - SSLEngine Supported Protocols: [SSLV3, TLSV1, TLSV1.1, TLSV1.2]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:740 - Application Specified Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:741 - Enabled Protocols: [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:748 - The following specified cipher suites are not supported: [TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:752 - SSLEngine Supported Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384, TLS_RSA_WITH_AES_256_CBC_SHA]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:753 - Application Specified Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_3DES_EDE_CBC_SHA, SSL_RSA_WITH_3DES_EDE_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, SSL_RSA_WITH_RC4_128_SHA, SSL_RSA_WITH_RC4_128_MD5, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:22,081 INFO Context_2_Thread_reconnect_service c.s.j.p.s.SSLSmfClient:754 - Enabled Cipher Suites: [TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA384, TLS_ECDHE_RSA_WITH_AES_256_CBC_SHA, TLS_RSA_WITH_AES_256_CBC_SHA256, TLS_RSA_WITH_AES_256_CBC_SHA, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA256, TLS_ECDHE_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_CBC_SHA256, TLS_RSA_WITH_AES_128_CBC_SHA, TLS_RSA_WITH_AES_128_GCM_SHA256, TLS_RSA_WITH_AES_256_GCM_SHA384, TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256, TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384]
2024-07-18 20:39:22,135 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Connected to host 'orig=tcps://localhost:32790, scheme=tcps://, host=localhost, port=32790' (smfclient 251)
2024-07-18 20:39:22,136 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_UP', Info: 'OK', ResponseCode: '200', Exception: 'null'].
2024-07-18 20:39:22,136 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:22,136 DEBUG Context_2_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@2a12941a): Received Solace Flow event [Type: 'FLOW_ACTIVE', Info: 'Flow becomes active', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:22,185 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTED', Info: 'null', ResponseCode: '0', Exception: 'null']
2024-07-18 20:39:22,185 DEBUG Context_2_Thread_reconnect_notify_service c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:39:32,077 INFO Thread-16 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:174 - Forcing Session Reconnect for client: fv-az1208-52/6734/012500fc/n4w6YP5h4j
2024-07-18 20:39:32,097 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 55244 Remote addr: localhost Remote port: 32790) - handleException(): Channel Closed Event (smfclient 252)
2024-07-18 20:39:32,097 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: handleException(): Channel Closed Event (smfclient 252) with exception: null cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1448) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:32,097 INFO Context_3_ReactorThread c.s.j.p.i.TcpClientChannel:63 - Client-252: (Client name: fv-az1208-52/6734/012500fc/n4w6YP5h4j Local port: 55244 Remote addr: localhost Remote port: 32790) - startReconnect(): Channel Closed Event (smfclient 252); exception: Error receiving data from underlying connection. cur_stack=Thread: "Context_3_ReactorThread" daemon id=293, prio=5: com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.startReconnect(TcpClientChannel.java:1409) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1473) com.solacesystems.jcsmp.protocol.impl.TcpClientChannel.handleException(TcpClientChannel.java:1399) com.solacesystems.jcsmp.protocol.nio.impl.SubscriberMessageReader.handleClosedSocketDuringRead(SubscriberMessageReader.java:209) com.solacesystems.jcsmp.protocol.smf.SimpleSmfClient.handleClosedSocketDuringRead(SimpleSmfClient.java:1199) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.processReactorChannels(SyncEventDispatcherReactor.java:229) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor.eventLoop(SyncEventDispatcherReactor.java:157) com.solacesystems.jcsmp.protocol.nio.impl.SyncEventDispatcherReactor$SEDReactorThread.run(SyncEventDispatcherReactor.java:338) java.base/java.lang.Thread.run(Thread.java:840)
2024-07-18 20:39:32,098 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_INACTIVE', Info: 'Flow becomes inactive due to: Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'null'].
2024-07-18 20:39:32,098 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.u.SolaceFlowEventHandler:23 - (com.solacesystems.jcsmp.impl.flow.FlowHandleImpl@1e0c45b9): Received Solace Flow event [Type: 'FLOW_DOWN', Info: 'Error receiving data from underlying connection.', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.'].
2024-07-18 20:39:32,098 DEBUG Context_3_ConsumerDispatcher c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is DOWN
2024-07-18 20:39:32,098 DEBUG Context_3_Thread_reconnect_service c.s.s.c.s.b.h.h.SolaceSessionEventHandler:25 - Received Solace JCSMP Session event [Type: 'RECONNECTING', Info: 'null', ResponseCode: '0', Exception: 'com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.']
2024-07-18 20:39:32,098 INFO Context_3_Thread_reconnect_service c.s.j.DefaultSolaceSessionOAuth2TokenProvider:50 - Fetching OAuth2 access token using client registration ID: solace2-oauth2-client
MockHttpServletRequest:
HTTP Method = GET
Request URI = /actuator/health
Parameters = {}
Headers = []
Body = null
Session Attrs = {}
Handler:
Type = org.springframework.boot.actuate.endpoint.web.servlet.AbstractWebMvcEndpointHandlerMapping$OperationHandler
Method = Actuator web endpoint 'health'
Async:
Async started = false
Async result = null
Resolved Exception:
Type = null
ModelAndView:
View name = null
View = null
Model = null
FlashMap:
Attributes = null
MockHttpServletResponse:
Status = 503
Error message = null
Headers = [Vary:"Origin", "Access-Control-Request-Method", "Access-Control-Request-Headers", Content-Type:"application/vnd.spring-boot.actuator.v3+json", X-Content-Type-Options:"nosniff", X-XSS-Protection:"0", Cache-Control:"no-cache, no-store, max-age=0, must-revalidate", Pragma:"no-cache", Expires:"0", X-Frame-Options:"DENY"]
Content type = application/vnd.spring-boot.actuator.v3+json
Body = {"status":"DOWN","components":{"binders":{"status":"DOWN","components":{"solace1":{"status":"UP","components":{"bindings":{"status":"UP","components":{"consume-in-0":{"status":"UP","components":{"flows":{"status":"UP","components":{"flow-0":{"status":"UP"}}}}}}},"connection":{"status":"UP"}}},"solace2":{"status":"DOWN","components":{"bindings":{"status":"DOWN","components":{"otherConsume-in-0":{"status":"DOWN","components":{"flows":{"status":"DOWN","components":{"flow-0":{"status":"DOWN","details":{"error":"com.solacesystems.jcsmp.JCSMPTransportException: Error receiving data from underlying connection.","info":"Error receiving data from underlying connection."}}}}}}}},"connection":{"status":"UP"}}}}},"diskSpace":{"status":"UP","details":{"total":77851254784,"free":15104806912,"threshold":10485760,"path":"/home/runner/work/solace-spring-cloud/solace-spring-cloud/solace-spring-cloud-stream-binder/solace-spring-cloud-stream-binder/.","exists":true}},"ping":{"status":"UP"}}}
Forwarded URL = null
Redirected URL = null
Cookies = []
2024-07-18 20:39:32,114 INFO ForkJoinPool-1-worker-6 c.s.s.c.s.b.s.o.MultiBinderOAuth2IT:39 - Ended Test: MultiBinderOAuth2IT.checkHealthOfMultipleSolaceBindersWhenForceReconnect(MockMvc)
github-actions / Test Results
checkSolaceMetricsAreExposed{MockMvc} (com.solace.spring.cloud.stream.binder.springBootTests.multibinder.MultiBinderIT) failed
solace-spring-cloud-stream-binder/solace-spring-cloud-stream-binder/target/failsafe-reports/TEST-com.solace.spring.cloud.stream.binder.springBootTests.multibinder.MultiBinderIT.xml [took 0s]
Raw output
Multiple Exceptions (2):
JSON path "names"
Expected: a collection containing "solace.message.size.payload"
but: mismatches were: [was "application.ready.time", was "application.started.time", was "disk.free", was "disk.total", was "executor.active", was "executor.completed", was "executor.pool.core", was "executor.pool.max", was "executor.pool.size", was "executor.queue.remaining", was "executor.queued", was "http.server.requests.active", was "jvm.buffer.count", was "jvm.buffer.memory.used", was "jvm.buffer.total.capacity", was "jvm.classes.loaded", was "jvm.classes.unloaded", was "jvm.compilation.time", was "jvm.gc.live.data.size", was "jvm.gc.max.data.size", was "jvm.gc.memory.allocated", was "jvm.gc.memory.promoted", was "jvm.gc.overhead", was "jvm.gc.pause", was "jvm.info", was "jvm.memory.committed", was "jvm.memory.max", was "jvm.memory.usage.after.gc", was "jvm.memory.used", was "jvm.threads.daemon", was "jvm.threads.live", was "jvm.threads.peak", was "jvm.threads.started", was "jvm.threads.states", was "logback.events", was "process.cpu.time", was "process.cpu.usage", was "process.files.max", was "process.files.open", was "process.start.time", was "process.uptime", was "spring.integration.channels", was "spring.integration.handlers", was "spring.integration.sources", was "spring.security.authorizations", was "spring.security.authorizations.active", was "spring.security.filterchains", was "spring.security.filterchains.access.exceptions.before", was "spring.security.filterchains.active", was "spring.security.filterchains.authentication.anonymous.before", was "spring.security.filterchains.authorization.before", was "spring.security.filterchains.context.async.before", was "spring.security.filterchains.context.holder.before", was "spring.security.filterchains.context.servlet.before", was "spring.security.filterchains.cors.before", was "spring.security.filterchains.csrf.before", was "spring.security.filterchains.header.before", was "spring.security.filterchains.logout.before", was "spring.security.filterchains.requestcache.before", was "spring.security.filterchains.session.urlencoding.before", was "spring.security.http.secured.requests.active", was "system.cpu.count", was "system.cpu.usage", was "system.load.average.1m"]
JSON path "names"
Expected: a collection containing "solace.message.size.total"
but: mismatches were: [was "application.ready.time", was "application.started.time", was "disk.free", was "disk.total", was "executor.active", was "executor.completed", was "executor.pool.core", was "executor.pool.max", was "executor.pool.size", was "executor.queue.remaining", was "executor.queued", was "http.server.requests.active", was "jvm.buffer.count", was "jvm.buffer.memory.used", was "jvm.buffer.total.capacity", was "jvm.classes.loaded", was "jvm.classes.unloaded", was "jvm.compilation.time", was "jvm.gc.live.data.size", was "jvm.gc.max.data.size", was "jvm.gc.memory.allocated", was "jvm.gc.memory.promoted", was "jvm.gc.overhead", was "jvm.gc.pause", was "jvm.info", was "jvm.memory.committed", was "jvm.memory.max", was "jvm.memory.usage.after.gc", was "jvm.memory.used", was "jvm.threads.daemon", was "jvm.threads.live", was "jvm.threads.peak", was "jvm.threads.started", was "jvm.threads.states", was "logback.events", was "process.cpu.time", was "process.cpu.usage", was "process.files.max", was "process.files.open", was "process.start.time", was "process.uptime", was "spring.integration.channels", was "spring.integration.handlers", was "spring.integration.sources", was "spring.security.authorizations", was "spring.security.authorizations.active", was "spring.security.filterchains", was "spring.security.filterchains.access.exceptions.before", was "spring.security.filterchains.active", was "spring.security.filterchains.authentication.anonymous.before", was "spring.security.filterchains.authorization.before", was "spring.security.filterchains.context.async.before", was "spring.security.filterchains.context.holder.before", was "spring.security.filterchains.context.servlet.before", was "spring.security.filterchains.cors.before", was "spring.security.filterchains.csrf.before", was "spring.security.filterchains.header.before", was "spring.security.filterchains.logout.before", was "spring.security.filterchains.requestcache.before", was "spring.security.filterchains.session.urlencoding.before", was "spring.security.http.secured.requests.active", was "system.cpu.count", was "system.cpu.usage", was "system.load.average.1m"]
java.lang.AssertionError:
Multiple Exceptions (2):
JSON path "names"
Expected: a collection containing "solace.message.size.payload"
but: mismatches were: [was "application.ready.time", was "application.started.time", was "disk.free", was "disk.total", was "executor.active", was "executor.completed", was "executor.pool.core", was "executor.pool.max", was "executor.pool.size", was "executor.queue.remaining", was "executor.queued", was "http.server.requests.active", was "jvm.buffer.count", was "jvm.buffer.memory.used", was "jvm.buffer.total.capacity", was "jvm.classes.loaded", was "jvm.classes.unloaded", was "jvm.compilation.time", was "jvm.gc.live.data.size", was "jvm.gc.max.data.size", was "jvm.gc.memory.allocated", was "jvm.gc.memory.promoted", was "jvm.gc.overhead", was "jvm.gc.pause", was "jvm.info", was "jvm.memory.committed", was "jvm.memory.max", was "jvm.memory.usage.after.gc", was "jvm.memory.used", was "jvm.threads.daemon", was "jvm.threads.live", was "jvm.threads.peak", was "jvm.threads.started", was "jvm.threads.states", was "logback.events", was "process.cpu.time", was "process.cpu.usage", was "process.files.max", was "process.files.open", was "process.start.time", was "process.uptime", was "spring.integration.channels", was "spring.integration.handlers", was "spring.integration.sources", was "spring.security.authorizations", was "spring.security.authorizations.active", was "spring.security.filterchains", was "spring.security.filterchains.access.exceptions.before", was "spring.security.filterchains.active", was "spring.security.filterchains.authentication.anonymous.before", was "spring.security.filterchains.authorization.before", was "spring.security.filterchains.context.async.before", was "spring.security.filterchains.context.holder.before", was "spring.security.filterchains.context.servlet.before", was "spring.security.filterchains.cors.before", was "spring.security.filterchains.csrf.before", was "spring.security.filterchains.header.before", was "spring.security.filterchains.logout.before", was "spring.security.filterchains.requestcache.before", was "spring.security.filterchains.session.urlencoding.before", was "spring.security.http.secured.requests.active", was "system.cpu.count", was "system.cpu.usage", was "system.load.average.1m"]
JSON path "names"
Expected: a collection containing "solace.message.size.total"
but: mismatches were: [was "application.ready.time", was "application.started.time", was "disk.free", was "disk.total", was "executor.active", was "executor.completed", was "executor.pool.core", was "executor.pool.max", was "executor.pool.size", was "executor.queue.remaining", was "executor.queued", was "http.server.requests.active", was "jvm.buffer.count", was "jvm.buffer.memory.used", was "jvm.buffer.total.capacity", was "jvm.classes.loaded", was "jvm.classes.unloaded", was "jvm.compilation.time", was "jvm.gc.live.data.size", was "jvm.gc.max.data.size", was "jvm.gc.memory.allocated", was "jvm.gc.memory.promoted", was "jvm.gc.overhead", was "jvm.gc.pause", was "jvm.info", was "jvm.memory.committed", was "jvm.memory.max", was "jvm.memory.usage.after.gc", was "jvm.memory.used", was "jvm.threads.daemon", was "jvm.threads.live", was "jvm.threads.peak", was "jvm.threads.started", was "jvm.threads.states", was "logback.events", was "process.cpu.time", was "process.cpu.usage", was "process.files.max", was "process.files.open", was "process.start.time", was "process.uptime", was "spring.integration.channels", was "spring.integration.handlers", was "spring.integration.sources", was "spring.security.authorizations", was "spring.security.authorizations.active", was "spring.security.filterchains", was "spring.security.filterchains.access.exceptions.before", was "spring.security.filterchains.active", was "spring.security.filterchains.authentication.anonymous.before", was "spring.security.filterchains.authorization.before", was "spring.security.filterchains.context.async.before", was "spring.security.filterchains.context.holder.before", was "spring.security.filterchains.context.servlet.before", was "spring.security.filterchains.cors.before", was "spring.security.filterchains.csrf.before", was "spring.security.filterchains.header.before", was "spring.security.filterchains.logout.before", was "spring.security.filterchains.requestcache.before", was "spring.security.filterchains.session.urlencoding.before", was "spring.security.http.secured.requests.active", was "system.cpu.count", was "system.cpu.usage", was "system.load.average.1m"]
at com.solace.spring.cloud.stream.binder.springBootTests.multibinder.MultiBinderIT.checkSolaceMetricsAreExposed(MultiBinderIT.java:95)
2024-07-18 20:41:14,053 INFO ForkJoinPool-1-worker-1 o.s.t.c.s.AnnotationConfigContextLoaderUtils:83 - Could not detect default configuration classes for test class [com.solace.spring.cloud.stream.binder.springBootTests.multibinder.MultiBinderIT]: MultiBinderIT does not declare any static, non-private, non-final, nested classes annotated with @Configuration.
2024-07-18 20:41:14,067 INFO ForkJoinPool-1-worker-1 o.s.b.t.c.SpringBootTestContextBootstrapper:234 - Found @SpringBootConfiguration com.solace.spring.cloud.stream.binder.springBootTests.multibinder.SpringCloudStreamApp for test class com.solace.spring.cloud.stream.binder.springBootTests.multibinder.MultiBinderIT
2024-07-18 20:41:14,070 INFO ForkJoinPool-1-worker-3 t.solace/solace-pubsub-standard:latest:387 - Creating container for image: solace/solace-pubsub-standard:latest
2024-07-18 20:41:14,100 INFO ForkJoinPool-1-worker-3 t.solace/solace-pubsub-standard:latest:451 - Container solace/solace-pubsub-standard:latest is starting: b27a37fc011ed56f43162e94507507475fa2e5967f9cdc8b5c9b89e664590eaa
2024-07-18 20:41:14,245 INFO Context_2_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-251: Draining stale reconnection task, channel is closed (rcid=16).
2024-07-18 20:41:14,245 INFO Context_3_Thread_reconnect_service c.s.j.p.i.TcpClientChannel:63 - Client-252: Draining stale reconnection task, channel is closed (rcid=15).
2024-07-18 20:41:40,141 INFO ForkJoinPool-1-worker-3 t.solace/solace-pubsub-standard:latest:541 - Container solace/solace-pubsub-standard:latest started in PT26.071104944S
2024-07-18 20:41:40,142 INFO ForkJoinPool-1-worker-3 c.s.j.p.i.TcpClientChannel:63 - Client-253: Connecting to host 'orig=localhost, host=localhost' (host 1 of 1, smfclient 253, attempt 1 of 1, this_host_attempt: 1 of 1)
2024-07-18 20:41:40,144 INFO ForkJoinPool-1-worker-3 c.s.j.i.JCSMPBasicSession:63 - Client-253: AdCtrl version changes: 2->4
2024-07-18 20:41:40,144 INFO ForkJoinPool-1-worker-3 c.s.j.p.i.TcpClientChannel:63 - Client-253: Connected to host 'orig=localhost, host=localhost' (smfclient 253)
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v3.3.1)
2024-07-18 20:41:40,194 WARN ForkJoinPool-1-worker-1 o.s.b.t.j.DuplicateJsonObjectContextCustomizerFactory$DuplicateJsonObjectContextCustomizer:78 -
Found multiple occurrences of org.json.JSONObject on the class path:
jar:file:/home/runner/.m2/repository/org/json/json/20240303/json-20240303.jar!/org/json/JSONObject.class
jar:file:/home/runner/.m2/repository/com/vaadin/external/google/android-json/0.0.20131108.vaadin1/android-json-0.0.20131108.vaadin1.jar!/org/json/JSONObject.class
You may wish to exclude one of them to ensure predictable runtime behavior
2024-07-18 20:41:40,197 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.s.m.MultiBinderIT:50 - Starting MultiBinderIT using Java 17.0.12 with PID 6734 (started by runner in /home/runner/work/solace-spring-cloud/solace-spring-cloud/solace-spring-cloud-stream-binder/solace-spring-cloud-stream-binder)
2024-07-18 20:41:40,197 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.s.m.MultiBinderIT:51 - Running with Spring Boot v3.3.1, Spring v6.1.10
2024-07-18 20:41:40,197 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.s.m.MultiBinderIT:660 - The following 1 profile is active: "multibinder"
2024-07-18 20:41:40,427 INFO ForkJoinPool-1-worker-1 o.s.i.c.DefaultConfiguringBeanFactoryPostProcessor:292 - No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
2024-07-18 20:41:40,428 INFO ForkJoinPool-1-worker-1 o.s.i.c.DefaultConfiguringBeanFactoryPostProcessor:292 - No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
2024-07-18 20:41:40,560 INFO ForkJoinPool-1-worker-1 o.s.b.a.e.w.EndpointLinksResolver:60 - Exposing 2 endpoints beneath base path '/actuator'
2024-07-18 20:41:40,692 INFO ForkJoinPool-1-worker-1 o.s.c.s.m.DirectWithAttributesChannel:174 - Channel 'application.consume-in-0' has 1 subscriber(s).
2024-07-18 20:41:40,693 INFO ForkJoinPool-1-worker-1 o.s.c.s.m.DirectWithAttributesChannel:174 - Channel 'application.otherConsume-in-0' has 1 subscriber(s).
2024-07-18 20:41:40,737 INFO ForkJoinPool-1-worker-1 o.s.b.t.m.w.SpringBootMockServletContext:437 - Initializing Spring TestDispatcherServlet ''
2024-07-18 20:41:40,738 INFO ForkJoinPool-1-worker-1 o.s.t.w.s.TestDispatcherServlet:532 - Initializing Servlet ''
2024-07-18 20:41:40,739 INFO ForkJoinPool-1-worker-1 o.s.t.w.s.TestDispatcherServlet:554 - Completed initialization in 1 ms
2024-07-18 20:41:40,762 INFO ForkJoinPool-1-worker-1 o.s.i.e.EventDrivenConsumer:174 - Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2024-07-18 20:41:40,762 INFO ForkJoinPool-1-worker-1 o.s.i.c.PublishSubscribeChannel:174 - Channel 'application.errorChannel' has 1 subscriber(s).
2024-07-18 20:41:40,762 INFO ForkJoinPool-1-worker-1 o.s.i.e.EventDrivenConsumer:292 - started bean '_org.springframework.integration.errorLogger'
2024-07-18 20:41:40,763 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:329 - Creating binder: solace1
2024-07-18 20:41:40,763 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:345 - Constructing binder child context for solace1
2024-07-18 20:41:40,780 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceHealthIndicatorsConfiguration:37 - Creating Solace Connection Health Indicators Hierarchy
2024-07-18 20:41:40,784 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceHealthIndicatorsConfiguration:51 - Creating Solace Session Event Handler for monitoring Health
2024-07-18 20:41:40,787 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceMessageChannelBinderConfiguration:64 - Registering Solace Session Event handler on session
2024-07-18 20:41:40,788 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceMessageChannelBinderConfiguration:74 - Connecting JCSMP session JCSMPSession
2024-07-18 20:41:40,789 INFO ForkJoinPool-1-worker-1 c.s.j.p.i.TcpClientChannel:63 - Client-254: Connecting to host 'orig=tcp://localhost:32792, scheme=tcp://, host=localhost, port=32792' (host 1 of 1, smfclient 254, attempt 1 of 1, this_host_attempt: 1 of 1)
2024-07-18 20:41:40,791 INFO ForkJoinPool-1-worker-1 c.s.j.i.JCSMPBasicSession:63 - Client-254: AdCtrl version changes: 2->4
2024-07-18 20:41:40,792 INFO ForkJoinPool-1-worker-1 c.s.j.p.i.TcpClientChannel:63 - Client-254: Connected to host 'orig=tcp://localhost:32792, scheme=tcp://, host=localhost, port=32792' (smfclient 254)
2024-07-18 20:41:40,793 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:41:40,796 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:377 - Caching the binder: solace1
2024-07-18 20:41:40,824 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:148 - Creating durable QUEUE scst/wk/myConsumerGroup/plain/MultiBinder/Queue/1 for consumer group myConsumerGroup
2024-07-18 20:41:40,826 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:221 - Testing consumer flow connection to queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/1 (will not start it)
2024-07-18 20:41:40,828 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:226 - Connected test consumer flow to queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/1, closing it
2024-07-18 20:41:40,830 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.BinderErrorChannel:174 - Channel 'solace-1640294744.consume-in-0.errors' has 1 subscriber(s).
2024-07-18 20:41:40,830 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.BinderErrorChannel:174 - Channel 'solace-1640294744.consume-in-0.errors' has 2 subscriber(s).
2024-07-18 20:41:40,830 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:85 - Creating QUEUE consumer flows for 1 scst/wk/myConsumerGroup/plain/MultiBinder/Queue/1 <inbound adapter 22ba8440-0b2e-4205-b622-91e1f33be6c9>
2024-07-18 20:41:40,830 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:140 - Creating consumer 1 of 1 for inbound adapter 22ba8440-0b2e-4205-b622-91e1f33be6c9
2024-07-18 20:41:40,830 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.u.FlowReceiverContainer:75 - Binding flow receiver container 1b16b384-458b-40f0-b834-abfe29572d1d
2024-07-18 20:41:40,831 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.u.FlowReceiverContainer:82 - Flow receiver container 1b16b384-458b-40f0-b834-abfe29572d1d started in state 'Running'
2024-07-18 20:41:40,832 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:41:40,833 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:271 - Subscribing queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/1 to topic MultiBinder/Queue/1
2024-07-18 20:41:40,834 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:292 - started com.solace.spring.cloud.stream.binder.inbound.JCSMPInboundChannelAdapter@327c93b9
2024-07-18 20:41:40,835 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:329 - Creating binder: solace2
2024-07-18 20:41:40,835 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:345 - Constructing binder child context for solace2
2024-07-18 20:41:40,854 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceHealthIndicatorsConfiguration:37 - Creating Solace Connection Health Indicators Hierarchy
2024-07-18 20:41:40,858 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceHealthIndicatorsConfiguration:51 - Creating Solace Session Event Handler for monitoring Health
2024-07-18 20:41:40,861 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceMessageChannelBinderConfiguration:64 - Registering Solace Session Event handler on session
2024-07-18 20:41:40,862 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.c.SolaceMessageChannelBinderConfiguration:74 - Connecting JCSMP session JCSMPSession
2024-07-18 20:41:40,863 INFO ForkJoinPool-1-worker-1 c.s.j.p.i.TcpClientChannel:63 - Client-255: Connecting to host 'orig=tcp://localhost:32792, scheme=tcp://, host=localhost, port=32792' (host 1 of 1, smfclient 255, attempt 1 of 1, this_host_attempt: 1 of 1)
2024-07-18 20:41:40,865 INFO ForkJoinPool-1-worker-1 c.s.j.i.JCSMPBasicSession:63 - Client-255: AdCtrl version changes: 2->4
2024-07-18 20:41:40,865 INFO ForkJoinPool-1-worker-1 c.s.j.p.i.TcpClientChannel:63 - Client-255: Connected to host 'orig=tcp://localhost:32792, scheme=tcp://, host=localhost, port=32792' (smfclient 255)
2024-07-18 20:41:40,867 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:41:40,870 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.DefaultBinderFactory:377 - Caching the binder: solace2
2024-07-18 20:41:40,913 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:148 - Creating durable QUEUE scst/wk/myConsumerGroup/plain/MultiBinder/Queue/2 for consumer group myConsumerGroup
2024-07-18 20:41:40,915 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:221 - Testing consumer flow connection to queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/2 (will not start it)
2024-07-18 20:41:40,917 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:226 - Connected test consumer flow to queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/2, closing it
2024-07-18 20:41:40,918 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.BinderErrorChannel:174 - Channel 'solace-497481320.otherConsume-in-0.errors' has 1 subscriber(s).
2024-07-18 20:41:40,919 INFO ForkJoinPool-1-worker-1 o.s.c.s.b.BinderErrorChannel:174 - Channel 'solace-497481320.otherConsume-in-0.errors' has 2 subscriber(s).
2024-07-18 20:41:40,919 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:85 - Creating QUEUE consumer flows for 1 scst/wk/myConsumerGroup/plain/MultiBinder/Queue/2 <inbound adapter 162f58b4-b2f8-46a8-bcef-6ea1032817d1>
2024-07-18 20:41:40,919 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:140 - Creating consumer 1 of 1 for inbound adapter 162f58b4-b2f8-46a8-bcef-6ea1032817d1
2024-07-18 20:41:40,919 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.u.FlowReceiverContainer:75 - Binding flow receiver container ec96047d-13e7-4d34-bc6a-f9ae0a10df04
2024-07-18 20:41:40,919 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.u.FlowReceiverContainer:82 - Flow receiver container ec96047d-13e7-4d34-bc6a-f9ae0a10df04 started in state 'Running'
2024-07-18 20:41:40,922 DEBUG ForkJoinPool-1-worker-1 c.s.s.c.s.b.h.b.SolaceHealthIndicator:23 - Solace connection/flow status is UP
2024-07-18 20:41:40,923 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.p.SolaceEndpointProvisioner:271 - Subscribing queue scst/wk/myConsumerGroup/plain/MultiBinder/Queue/2 to topic MultiBinder/Queue/2
2024-07-18 20:41:40,924 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.i.JCSMPInboundChannelAdapter:292 - started com.solace.spring.cloud.stream.binder.inbound.JCSMPInboundChannelAdapter@109a1ade
2024-07-18 20:41:40,929 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.s.m.MultiBinderIT:56 - Started MultiBinderIT in 0.778 seconds (process running for 735.139)
2024-07-18 20:41:40,932 INFO ForkJoinPool-1-worker-3 c.s.s.c.s.b.s.m.MultiBinderIT:39 - Starting Test: MultiBinderIT.checkSolaceMetricsAreExposed(MockMvc)
2024-07-18 20:41:40,932 INFO ForkJoinPool-1-worker-1 c.s.s.c.s.b.s.m.MultiBinderIT:39 - Starting Test: MultiBinderIT.checkHealthOfMultipleSolaceBinders(MockMvc)
2024-07-18 20:41:40,943 DEBUG solace-scst-consumer-consume-in-01 c.s.s.c.s.b.u.XMLMessageMapper:315 - Null payload detected, setting Spring header solace_scst_nullPayload
2024-07-18 20:41:40,944 DEBUG solace-scst-consumer-consume-in-01 c.s.s.c.s.b.u.XMLMessageMapper:334 - Ignoring Solace header solace_deliveryCount. Error: Delivery count not supported on the Endpoint
[B@33441332
MockHttpServletRequest:
HTTP Method = GET
Request URI = /actuator/metrics
Parameters = {}
Headers = []
Body = null
Session Attrs = {}
Handler:
Type = org.springframework.boot.actuate.endpoint.web.servlet.AbstractWebMvcEndpointHandlerMapping$OperationHandler
Method = Actuator web endpoint 'metrics'
Async:
Async started = false
Async result = null
Resolved Exception:
Type = null
ModelAndView:
View name = null
View = null
Model = null
FlashMap:
Attributes = null
MockHttpServletResponse:
Status = 200
Error message = null
Headers = [Vary:"Origin", "Access-Control-Request-Method", "Access-Control-Request-Headers", Content-Type:"application/vnd.spring-boot.actuator.v3+json", X-Content-Type-Options:"nosniff", X-XSS-Protection:"0", Cache-Control:"no-cache, no-store, max-age=0, must-revalidate", Pragma:"no-cache", Expires:"0", X-Frame-Options:"DENY"]
Content type = application/vnd.spring-boot.actuator.v3+json
Body = {"names":["application.ready.time","application.started.time","disk.free","disk.total","executor.active","executor.completed","executor.pool.core","executor.pool.max","executor.pool.size","executor.queue.remaining","executor.queued","http.server.requests.active","jvm.buffer.count","jvm.buffer.memory.used","jvm.buffer.total.capacity","jvm.classes.loaded","jvm.classes.unloaded","jvm.compilation.time","jvm.gc.live.data.size","jvm.gc.max.data.size","jvm.gc.memory.allocated","jvm.gc.memory.promoted","jvm.gc.overhead","jvm.gc.pause","jvm.info","jvm.memory.committed","jvm.memory.max","jvm.memory.usage.after.gc","jvm.memory.used","jvm.threads.daemon","jvm.threads.live","jvm.threads.peak","jvm.threads.started","jvm.threads.states","logback.events","process.cpu.time","process.cpu.usage","process.files.max","process.files.open","process.start.time","process.uptime","spring.integration.channels","spring.integration.handlers","spring.integration.sources","spring.security.authorizations","spring.security.authorizations.active","spring.security.filterchains","spring.security.filterchains.access.exceptions.before","spring.security.filterchains.active","spring.security.filterchains.authentication.anonymous.before","spring.security.filterchains.authorization.before","spring.security.filterchains.context.async.before","spring.security.filterchains.context.holder.before","spring.security.filterchains.context.servlet.before","spring.security.filterchains.cors.before","spring.security.filterchains.csrf.before","spring.security.filterchains.header.before","spring.security.filterchains.logout.before","spring.security.filterchains.requestcache.before","spring.security.filterchains.session.urlencoding.before","spring.security.http.secured.requests.active","system.cpu.count","system.cpu.usage","system.load.average.1m"]}
Forwarded URL = null
Redirected URL = null
Cookies = []
2024-07-18 20:41:40,956 INFO ForkJoinPool-1-worker-3 c.s.s.c.s.b.s.m.MultiBinderIT:39 - Ended Test: MultiBinderIT.checkSolaceMetricsAreExposed(MockMvc)
Loading