Quantcast

EHR as responding system - Failed to create saml: null

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

EHR as responding system - Failed to create saml: null

shalinib
Hi,

Has anyone come across the below error. The connect is showing inbound request from DIL but the test is failing with  the below error message in the log.

20:00:16,330 WARN  [gov.hhs.fha.nhinc.callback.cxf.CONNECTSamlAssertionValidator] (http--0.0.0.0-8181-1) Could not establish trust of the signature's public key because no matching public key exists in the truststore. Please see GATEWAY-3146 for more details.
20:00:16,396 INFO  [gov.hhs.fha.nhinc.logging.transaction.TransactionLogger] (http--0.0.0.0-8181-1) pass in transaction-id is null for message id: urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b
20:00:16,413 WARN  [gov.hhs.fha.nhinc.openSAML.extraction.OpenSAMLAssertionExtractorImpl] (http--0.0.0.0-8181-1) Subject name format is not X509!
20:00:16,414 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,416 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,473 INFO  [gov.hhs.fha.nhinc.event.Log4jEventLogger] (http--0.0.0.0-8181-1) BEGIN_INBOUND_MESSAGE has triggered. It has messageID urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b, transactionID null and description {"initiating_hcid":"urn:oid:2.16.840.1.113883.3.1259.10.1003","responding_hcids":["urn:oid:1.1"],"service_type":"Patient Discovery","action":"1.0","message_id":"urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b"}
20:00:16,482 WARN  [gov.hhs.fha.nhinc.openSAML.extraction.OpenSAMLAssertionExtractorImpl] (http--0.0.0.0-8181-1) Subject name format is not X509!
20:00:16,484 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,486 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,528 INFO  [gov.hhs.fha.nhinc.event.Log4jEventLogger] (http--0.0.0.0-8181-1) BEGIN_DELEGATION_TO_ADAPTER has triggered. It has messageID urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b, transactionID null and description {"initiating_hcid":"urn:oid:2.16.840.1.113883.3.1259.10.1003","responding_hcids":["urn:oid:1.1"],"service_type":"Patient Discovery","action":"1.0","message_id":"urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b"}
20:00:16,559 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,560 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,598 INFO  [gov.hhs.fha.nhinc.event.Log4jEventLogger] (http--0.0.0.0-8181-1) END_DELEGATION_TO_ADAPTER has triggered. It has messageID urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b, transactionID null and description {"initiating_hcid":"urn:oid:2.16.840.1.113883.3.1259.10.1003","responding_hcids":[],"service_type":"Patient Discovery","action":"1.0","message_id":"urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b","statuses":[]}
20:00:16,609 ERROR [gov.hhs.fha.nhinc.patientdiscovery.parser.PRPAIN201306UV02Parser] (EJB default - 2) PatientId doesn't exist in the received PRPAIN201306UV02 message
20:00:16,612 WARN  [gov.hhs.fha.nhinc.openSAML.extraction.OpenSAMLAssertionExtractorImpl] (http--0.0.0.0-8181-1) Subject name format is not X509!
20:00:16,615 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,617 INFO  [gov.hhs.fha.nhinc.logging.transaction.dao.TransactionDAO] (http--0.0.0.0-8181-1) -- MessageId Parameter is required for Transaction Query --
20:00:16,701 INFO  [gov.hhs.fha.nhinc.saml.extraction.SamlTokenCreator] (EJB default - 2) AuthzDecisionStatement is null.  It will not be part of the SAML Assertion
20:00:16,727 INFO  [gov.hhs.fha.nhinc.event.Log4jEventLogger] (http--0.0.0.0-8181-1) END_INBOUND_MESSAGE has triggered. It has messageID urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b, transactionID null and description {"initiating_hcid":"urn:oid:2.16.840.1.113883.3.1259.10.1003","responding_hcids":[],"service_type":"Patient Discovery","action":"1.0","message_id":"urn:uuid:a8b9ae2e-2476-4b7b-905f-92de5fd6af4b","statuses":[]}
20:00:16,719 ERROR [gov.hhs.fha.nhinc.callback.openSAML.HOKSAMLAssertionBuilder] (EJB default - 2) Unable to create HOK Assertion: null
20:00:16,734 ERROR [gov.hhs.fha.nhinc.callback.cxf.CXFSAMLCallbackHandler] (EJB default - 2) Failed to create saml: null
20:00:16,782 INFO  [gov.hhs.fha.nhinc.logging.transaction.TransactionLogger] (http--0.0.0.0-8181-1) pass in transaction-id is null for message id: urn:uuid:42d6db33-0fdf-40d3-aae3-2db859aa1b41
20:00:16,775 WARN  [org.apache.cxf.phase.PhaseInterceptorChain] (EJB default - 2) Interceptor for {urn:gov:hhs:fha:nhinc:nhinccomponentauditrepository}AuditRepositoryManagerSecuredPortTypeService#{urn:gov:hhs:fha:nhinc:nhinccomponentauditrepository}LogEvent has thrown exception, unwinding now: java.lang.NullPointerException
        at org.apache.ws.security.saml.ext.builder.SAML2ComponentBuilder.createNameID(SAML2ComponentBuilder.java:538) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.ws.security.saml.ext.builder.SAML2ComponentBuilder.createSaml2Subject(SAML2ComponentBuilder.java:389) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.ws.security.saml.ext.AssertionWrapper.parseCallback(AssertionWrapper.java:821) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.ws.security.saml.ext.AssertionWrapper.<init>(AssertionWrapper.java:242) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.ws.security.saml.SAMLIssuerImpl.newAssertion(SAMLIssuerImpl.java:145) [wss4j-1.6.9.jar:1.6.9]
        at gov.hhs.fha.nhinc.saml.SAMLIssuerImpl.newAssertion(SAMLIssuerImpl.java:79) [CONNECTCoreLib-4.7.0.jar:]
        at org.apache.ws.security.action.SAMLTokenSignedAction.execute(SAMLTokenSignedAction.java:61) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.ws.security.handler.WSHandler.doSenderAction(WSHandler.java:230) [wss4j-1.6.9.jar:1.6.9]
        at org.apache.cxf.ws.security.wss4j.WSS4JOutInterceptor.access$200(WSS4JOutInterceptor.java:52) [cxf-rt-ws-security-2.7.3.jar:2.7.3]
        at org.apache.cxf.ws.security.wss4j.WSS4JOutInterceptor$WSS4JOutInterceptorInternal.handleMessage(WSS4JOutInterceptor.java:260) [cxf-rt-ws-security-2.7.3.jar:2.7.3]
        at org.apache.cxf.ws.security.wss4j.WSS4JOutInterceptor$WSS4JOutInterceptorInternal.handleMessage(WSS4JOutInterceptor.java:136) [cxf-rt-ws-security-2.7.3.jar:2.7.3]
        at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:271) [cxf-api-2.7.3.jar:2.7.3]
        at org.apache.cxf.endpoint.ClientImpl.doInvoke(ClientImpl.java:530) [cxf-api-2.7.3.jar:2.7.3]
        at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:463) [cxf-api-2.7.3.jar:2.7.3]
        at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:366) [cxf-api-2.7.3.jar:2.7.3]
        at org.apache.cxf.endpoint.ClientImpl.invoke(ClientImpl.java:319) [cxf-api-2.7.3.jar:2.7.3]
        at org.apache.cxf.frontend.ClientProxy.invokeSync(ClientProxy.java:96) [cxf-rt-frontend-simple-2.7.3.jar:2.7.3]
        at org.apache.cxf.jaxws.JaxWsClientProxy.invoke(JaxWsClientProxy.java:133) [cxf-rt-frontend-jaxws-2.7.3.jar:2.7.3]
        at $Proxy241.logEvent(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_03]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_03]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_03]
        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_03]
        at gov.hhs.fha.nhinc.webserviceproxy.WebServiceProxyHelper.invokeTheMethod(WebServiceProxyHelper.java:271) [CONNECTCoreLib-4.7.0.jar:]
        at gov.hhs.fha.nhinc.webserviceproxy.WebServiceProxyHelper.invokePort(WebServiceProxyHelper.java:351) [CONNECTCoreLib-4.7.0.jar:]
        at gov.hhs.fha.nhinc.webserviceproxy.WebServiceProxyHelper.invokePortWithRetry(WebServiceProxyHelper.java:399) [CONNECTCoreLib-4.7.0.jar:]
        at gov.hhs.fha.nhinc.webserviceproxy.WebServiceProxyHelper.invokePort(WebServiceProxyHelper.java:325) [CONNECTCoreLib-4.7.0.jar:]
        at gov.hhs.fha.nhinc.messaging.client.CONNECTBaseClient.invokePort(CONNECTBaseClient.java:54) [CONNECTCoreLib-4.7.0.jar:]
        at gov.hhs.fha.nhinc.auditrepository.nhinc.proxy.AuditRepositoryProxyWebServiceSecuredImpl.auditLog(AuditRepositoryProxyWebServiceSecuredImpl.java:73) [AuditRepositoryCore-4.7.0.jar:]
        at gov.hhs.fha.nhinc.audit.ejb.impl.AuditEJBLoggerImpl.auditLogMessages(AuditEJBLoggerImpl.java:110) [AuditRepositoryEJB-4.7.0.jar:]
        at gov.hhs.fha.nhinc.audit.ejb.impl.AuditEJBLoggerImpl.auditResponseMessage(AuditEJBLoggerImpl.java:104) [AuditRepositoryEJB-4.7.0.jar:]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_03]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_03]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_03]
        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_03]
        at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47)
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.component.pool.PooledInstanceInterceptor.processInvocation(PooledInstanceInterceptor.java:51) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:304) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:190) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:43) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
        at org.jboss.as.ejb3.component.interceptors.AsyncVoidInterceptorFactory$Task.run(AsyncVoidInterceptorFactory.java:75) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.7.0_03]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.7.0_03]
        at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_03]
        at org.jboss.threads.JBossThread.run(JBossThread.java:122)

20:00:16,816 ERROR [gov.hhs.fha.nhinc.webserviceproxy.WebServiceProxyHelper] (EJB default - 2) An unexpected exception occurred of type javax.xml.ws.soap.SOAPFaultException: Fault string, and possibly fault code, not set
20:00:16,817 ERROR [gov.hhs.fha.nhinc.auditrepository.nhinc.proxy.AuditRepositoryProxyWebServiceSecuredImpl] (EJB default - 2) Failed to call the web service(auditrepositorysecured). An unexpected exception occurred. Exception: Fault string, and possibly fault code, not set
20:02:09,283 INFO  [org.apache.myfaces.shared.renderkit.RendererUtils] (http--0.0.0.0-8181-1) Unable to find component 'input-username' (calling findComponent on component 'j_id_x'). We'll try to return a guessed client-id anyways - this will be a problem if you put the referenced component into a different naming-container. If this is the case, you can always use the full client-id.
20:02:09,285 INFO  [org.apache.myfaces.shared.renderkit.RendererUtils] (http--0.0.0.0-8181-1) Unable to find component 'input-password' (calling findComponent on component 'j_id_11'). We'll try to return a guessed client-id anyways - this will be a problem if you put the referenced component into a different naming-container. If this is the case, you can always use the full client-id.
20:02:12,643 INFO  [stdout] (http--0.0.0.0-8181-1) Hibernate: select userlogin0_.ID as ID1_1_, userlogin0_.SALT as SALT2_1_, userlogin0_.SHA2 as SHA3_1_, userlogin0_.USERNAME as USERNAME4_1_, userlogin0_.userRole as userRole5_1_ from UserLogin userlogin0_ where (userName=? )

20:02:12,688 INFO  [stdout] (http--0.0.0.0-8181-1) Hibernate: select userrole0_.ROLEID as ROLEID1_2_0_, userrole0_.ROLENAME as ROLENAME2_2_0_, preference1_.prefRoleId as prefRole5_0_1_, preference1_.PREFID as PREFID1_0_1_, preference1_.PREFID as PREFID1_0_2_, preference1_.ACCESSPAGE as ACCESSPA2_0_2_, preference1_.PAGEDESC as PAGEDESC3_0_2_, preference1_.PAGENAME as PAGENAME4_0_2_, preference1_.prefRoleId as prefRole5_0_2_, userlogins2_.userRole as userRole5_1_3_, userlogins2_.ID as ID1_1_3_, userlogins2_.ID as ID1_1_4_, userlogins2_.SALT as SALT2_1_4_, userlogins2_.SHA2 as SHA3_1_4_, userlogins2_.USERNAME as USERNAME4_1_4_, userlogins2_.userRole as userRole5_1_4_ from UserRole userrole0_ left outer join PagePreference preference1_ on userrole0_.ROLEID=preference1_.prefRoleId left outer join UserLogin userlogins2_ on userrole0_.ROLEID=userlogins2_.userRole where userrole0_.ROLEID=?

20:02:12,780 INFO  [gov.hhs.fha.nhinc.admingui.hibernate.LoginServiceImpl] (http--0.0.0.0-8181-1) Prepare to check user credential



Thanks
Shalini
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: EHR as responding system - Failed to create saml: null

Minh
Administrator
I think you have similar issue under http://forums.connectopensource.org/EHR-as-responding-system-td7580587.html.  Please see my latest comments to validate your configuration.

I suggest you run ValidationSuite to validate your setting before testing DIL test.  The ValidationSuite locates under the zip binary "ftp://ftp.connectopensource.org/4.7.0/CONNECT-4.7.0-JB7.zip"

Thanks,
Minh-Hai Nguyen
CONNECT Product Team Member
Loading...