search

OxalisCommunity / oxalis

Oxalis - PEPPOL Access Point open source implementation - Core component
Other
134 stars 92 forks source link

example.sh - oxalis-standalone.jar - An Error occurred while locating default certificate and privateKey in KeyStore #242

Closed ghost closed 9 years ago

ghost commented 9 years ago

Hi, I am trying to execute example.sh to send sample invoice.. Similar to the issue 47 (https://github.com/difi/oxalis/issues/47) I have java version "1.7.0_75" Java(TM) SE Runtime Environment (build 1.7.0_75-b13) Java HotSpot(TM) 64-Bit Server VM (build 24.75-b04, mixed mode)

issue 47, fix is related to java version, do I need to have specific java version? $/oxalis/bin # ./example.sh -t

Sending...
File BII04_T10_EHF-v1.5_invoice.xml
Sender: 9908:810017902
Reciever: 9908:810017902
Destination: https://XXXXXXX/oxalis/accessPointService
Method (protocol): start

Executing .... java -jar oxalis-standalone.jar -f BII04_T10_EHF-v1.5_invoice.xml -r 9908:810017902 -s 9908:810017902 -u https://XXXXXXX/oxalis/accessPointService -m start -t

15:16:18,488 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy] 15:16:18,488 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/home/oxalis/bin/oxalis-standalone.jar!/logback-test.xml] 15:16:18,507 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@673a26c2 - URL [jar:file:/home/oxalis/bin/oxalis-standalone.jar!/logback-test.xml] is not of type file 15:16:18,609 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:16:18,613 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 15:16:18,638 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-tes] to DEBUG 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-com] to DEBUG 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-out] to DEBUG 15:16:18,701 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to DEBUG 15:16:18,701 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT] 15:16:18,702 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 15:16:18,703 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@38c682d9 - Registering current configuration as safe fallback point 2015-11-30 15:16:19,101 INFO [eu.peppol.util.GlobalConfiguration] Initialising the Oxalis global configuration .... 2015-11-30 15:16:19,104 DEBUG [eu.peppol.util.OxalisHomeDirectory] Attempting to locate home dir.... 2015-11-30 15:16:19,105 INFO [eu.peppol.util.GlobalConfiguration] Oxalis home directory: /home/oxalis 2015-11-30 15:16:19,108 INFO [eu.peppol.util.GlobalConfiguration] oxalis.keystore = /usr/local/keys/xxxx.jks 2015-11-30 15:16:19,108 INFO [eu.peppol.util.GlobalConfiguration] oxalis.keystore.password = XXXXX 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.truststore.password = peppol 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.inbound.message.store = /home/oxalis-message-store 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.persistence.class.path = 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.soap.trace = false 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.driver.class = oracle.jdbc.driver.OracleDriver 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.connection.uri = jdbc:oracle:thin:@xxxx.com:1521:DEV 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.user = oxalis 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.password = oxalis 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.class.path = file:////opt/tomcat/lib/ojdbc6.jar 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.validation.query = select 1 from dual 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.dialect = Oracle 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.datasource.jndi.name = jdbc/oxalisDS 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.statistics.private.key = 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.inbound.log.config = /home/oxalis/logback-oxalis-server.xml 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.app.log.config = /home/oxalis/logback-oxalis-app.xml 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.pki.version = V2 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.operation.mode = TEST 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.connection.timeout = 5000 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.read.timeout = 5000 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.sml.hostname = 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] Verifying properties .... 2015-11-30 15:16:19,110 WARN [eu.peppol.smp.SmpLookupManagerImpl] Mode of operation is TEST 2015-11-30 15:16:19,110 DEBUG [eu.peppol.smp.SmpLookupManagerImpl] SML hostname: smk.peppolcentral.org Request builder of messages to the debug log is : false 2015-11-30 15:16:19,538 DEBUG [eu.peppol.document.PlainUBLHeaderParser] Creating DocumentParser for type : Invoice 2015-11-30 15:16:19,608 WARN [eu.peppol.outbound.transmission.TransmissionRequestBuilder] Endpoint was set by caller not retrieved from SMP, make sure this is intended behaviour. 2015-11-30 15:16:19,642 WARN [eu.peppol.smp.SmpLookupManagerImpl] Mode of operation is TEST 2015-11-30 15:16:19,643 DEBUG [eu.peppol.smp.SmpLookupManagerImpl] SML hostname: smk.peppolcentral.org 2015-11-30 15:16:19,644 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Searching the class path for instances of RawStatisticsRepositoryFactory 2015-11-30 15:16:19,646 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Found a RawStatisticsRepositoryFactory implementation in eu.peppol.persistence.sql.RawStatisticsRepositoryFactoryJdbcImpl 2015-11-30 15:16:19,646 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Since RawStatisticsRepositoryFactoryJdbcImpl is the built-in implementation, we will look for other 3rd party implementaions 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Loading instance of eu.peppol.jdbc.OxalisDataSourceFactory from class path using META-INF/services idiom 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Looking for META-INF/services/eu.peppol.jdbc.OxalisDataSourceFactory in classpath ... 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Found it in jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/services/eu.peppol.jdbc.OxalisDataSourceFactory 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] Configuring DataSource wrapped in a Database Connection Pool, using custom loader 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] Loading JDBC Driver with custom class path: file:////opt/tomcat/lib/ojdbc6.jar 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] className=oracle.jdbc.driver.OracleDriver 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] connectURI=jdbc:oracle:thin:@xxx.elcom.com:1521:DEV 2015-11-30 15:16:19,652 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] userName=oxalis 2015-11-30 15:16:19,652 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] password=oxalis 2015-11-30 15:16:19,825 DEBUG [eu.peppol.security.PeppolTrustStore] Loading and combining trust stores: truststore-test.jks 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-root-0 for certificate CN=PEPPOL Root TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-smp-0 for certificate CN=PEPPOL SERVICE METADATA PUBLISHER TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-ap-0 for certificate CN=PEPPOL ACCESS POINT TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,856 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-sts-0 for certificate CN=PEPPOL SECURITY TOKEN SERVICE TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,856 DEBUG [eu.peppol.security.KeystoreManager] Loading PEPPOL keystore from /usr/local/keys/oxalis.jks 2015-11-30 15:16:19,875 DEBUG [eu.peppol.outbound.transmission.StartMessageSender] Constructing document body.... 2015-11-30 15:16:19,911 DEBUG [oxalis-out] Constructing SOAP header 2015-11-30 15:16:20,087 DEBUG [oxalis-out] Constructing service proxy 2015-11-30 15:16:20,089 DEBUG [oxalis-out] Found WSDL file at jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/wsdl/wsdl_v2.0.wsdl Nov 30, 2015 3:16:20 PM [com.sun.xml.ws.policy.parser.PolicyConfigParser] parse INFO: WSP5018: Loaded WSIT configuration from file: jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/wsit-client.xml. 2015-11-30 15:16:20,621 DEBUG [oxalis-out] Getting remote resource binding port 2015-11-30 15:16:21,832 DEBUG [eu.peppol.security.callbacks.TrustStoreCallbackHandler] Initializing the TrustStoreCallbackHandler 2015-11-30 15:16:22,250 DEBUG [oxalis-out] setting connectTimeout 5000 readTimeout 5000 2015-11-30 15:16:22,251 INFO [oxalis-out] Performing SOAP request to: https://xxxx.oxalis.com/oxalis/accessPointService 2015-11-30 15:16:22,276 DEBUG [oxalis-out] SOAP outbound handler called 2015-11-30 15:16:22,277 DEBUG [oxalis-out] Adding BUSDOX headers to SOAP-envelope 2015-11-30 15:16:22,578 DEBUG [eu.peppol.security.callbacks.SAMLCallbackHandler] Requested SAML callback handling 2015-11-30 15:16:22,578 DEBUG [eu.peppol.security.callbacks.SAMLCallbackHandler] Creating and setting the SAML Sender Vouches Assertion 2015-11-30 15:16:23,107 DEBUG [eu.peppol.security.callbacks.KeyStoreCallbackHandler] Keystore callback handler: returning keystore Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultCallbackHandler getDefaultPrivKeyCert SEVERE: WSS1512: An Error occurred while locating default certificate and private key in KeyStore. Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultCallbackHandler getDefaultPrivKeyCert SEVERE: WSS1505: IO Exception occured: failed to get key/certificate from KeyStore (not necessarily i/o exception) java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138)

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl getDefaultPrivKeyCertRequest SEVERE: WSS0216: An Error occurred using CallbackHandler for : SignatureKeyCallback.DefaultPrivKeyCertRequest Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl getDefaultPrivKeyCertRequest SEVERE: WSS0217: An Error occurred using CallbackHandler handle() Method. java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.filter.SignatureFilter process SEVERE: WSS1417: Error while processing signature [Ljava.lang.Object;@37900c04 Nov 30, 2015 3:16:23 PM com.sun.xml.wss.jaxws.impl.SecurityTubeBase secureOutboundMessage SEVERE: WSSTUBE0024: Error in Securing Outbound Message. com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.jaxws.impl.SecurityClientTube processClientRequestPacket SEVERE: WSSTUBE0024: Error in Securing Outbound Message. com.sun.xml.wss.impl.WssSoapFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.SecurableSoapMessage.newSOAPFaultException(SecurableSoapMessage.java:349) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:393) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) ... 28 more Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM [com.sun.xml.ws.rx.rm.runtime.ClientTube] doRun SEVERE: WSRM1106: Attempt to send a [ CreateSequence ] RM session control message failed with an exception javax.xml.ws.WebServiceException: WSSTUBE0024: Error in Securing Outbound Message. at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:328) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: javax.xml.ws.soap.SOAPFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.getSOAPFaultException(SecurityTubeBase.java:715) ... 28 more Caused by: com.sun.xml.wss.impl.WssSoapFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.SecurableSoapMessage.newSOAPFaultException(SecurableSoapMessage.java:349) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:393) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) ... 27 more Caused by: com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) ... 28 more Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Message failed : WSRM1106: Attempt to send a [ CreateSequence ] RM session control message failed with an exception

ghost commented 9 years ago

Hi, Any help is highly appreciated. Thanks in advance.

steinarcook commented 9 years ago

You have a problem related to the installation of your PEPPOL certificate.

On 30 November 2015 at 21:37, ahmedinweb notifications@github.com wrote:

Hi, I am trying to execute example.sh to send sample invoice.. Similar to the issue 47 (#47 https://github.com/difi/oxalis/issues/47) I have java version "1.7.0_75" Java(TM) SE Runtime Environment (build 1.7.0_75-b13) Java HotSpot(TM) 64-Bit Server VM (build 24.75-b04, mixed mode)

issue 47, fix is related to java version, do I need to have specific java version? $/oxalis/bin # ./example.sh -t

Sending... File BII04_T10_EHF-v1.5_invoice.xml Sender: 9908:810017902 Reciever: 9908:810017902 Destination: https://XXXXXXX/oxalis/accessPointService Method (protocol): start

Executing .... java -jar oxalis-standalone.jar -f BII04_T10_EHF-v1.5_invoice.xml -r 9908:810017902 -s 9908:810017902 -u https://XXXXXXX/oxalis/accessPointService -m start -t

15:16:18,488 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy] 15:16:18,488 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/home/oxalis/bin/oxalis-standalone.jar!/logback-test.xml] 15:16:18,507 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@673a26c https://github.com/ch.qos.logback.core.joran.spi.ConfigurationWatchList/oxalis/commit/673a26c2

  • URL [jar:file:/home/oxalis/bin/oxalis-standalone.jar!/logback-test.xml] is not of type file 15:16:18,609 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:16:18,613 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 15:16:18,638 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-tes] to DEBUG 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-com] to DEBUG 15:16:18,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [oxalis-out] to DEBUG 15:16:18,701 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to DEBUG 15:16:18,701 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction
  • Attaching appender named [STDOUT] to Logger[ROOT] 15:16:18,702 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 15:16:18,703 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@ 38c682d https://github.com/ch.qos.logback.classic.joran.JoranConfigurator/oxalis/commit/38c682d9
  • Registering current configuration as safe fallback point 2015-11-30 15:16:19,101 INFO [eu.peppol.util.GlobalConfiguration] Initialising the Oxalis global configuration .... 2015-11-30 15:16:19,104 DEBUG [eu.peppol.util.OxalisHomeDirectory] Attempting to locate home dir.... 2015-11-30 15:16:19,105 INFO [eu.peppol.util.GlobalConfiguration] Oxalis home directory: /home/oxalis 2015-11-30 15:16:19,108 INFO [eu.peppol.util.GlobalConfiguration] oxalis.keystore = /usr/local/keys/xxxx.jks 2015-11-30 15:16:19,108 INFO [eu.peppol.util.GlobalConfiguration] oxalis.keystore.password = XXXXX 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.truststore.password = peppol 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.inbound.message.store = /home/oxalis-message-store 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.persistence.class.path = 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.soap.trace = false 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.driver.class = oracle.jdbc.driver.OracleDriver 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.connection.uri = jdbc:oracle:thin:@xxxx.com:1521:DEV 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.user = oxalis 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.password = oxalis 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.class.path = file:////opt/tomcat/lib/ojdbc6.jar 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.validation.query = select 1 from dual 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.jdbc.dialect = Oracle 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.datasource.jndi.name = jdbc/oxalisDS 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.statistics.private.key = 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.inbound.log.config = /home/oxalis/logback-oxalis-server.xml 2015-11-30 15:16:19,109 INFO [eu.peppol.util.GlobalConfiguration] oxalis.app.log.config = /home/oxalis/logback-oxalis-app.xml 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.pki.version = V2 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.operation.mode = TEST 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.connection.timeout = 5000 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.read.timeout = 5000 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] oxalis.sml.hostname = 2015-11-30 15:16:19,110 INFO [eu.peppol.util.GlobalConfiguration] Verifying properties .... 2015-11-30 15:16:19,110 WARN [eu.peppol.smp.SmpLookupManagerImpl] Mode of operation is TEST 2015-11-30 15:16:19,110 DEBUG [eu.peppol.smp.SmpLookupManagerImpl] SML hostname: smk.peppolcentral.org Request builder of messages to the debug log is : false 2015-11-30 15:16:19,538 DEBUG [eu.peppol.document.PlainUBLHeaderParser] Creating DocumentParser for type : Invoice 2015-11-30 15:16:19,608 WARN [eu.peppol.outbound.transmission.TransmissionRequestBuilder] Endpoint was set by caller not retrieved from SMP, make sure this is intended behaviour. 2015-11-30 15:16:19,642 WARN [eu.peppol.smp.SmpLookupManagerImpl] Mode of operation is TEST 2015-11-30 15:16:19,643 DEBUG [eu.peppol.smp.SmpLookupManagerImpl] SML hostname: smk.peppolcentral.org 2015-11-30 15:16:19,644 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Searching the class path for instances of RawStatisticsRepositoryFactory 2015-11-30 15:16:19,646 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Found a RawStatisticsRepositoryFactory implementation in eu.peppol.persistence.sql.RawStatisticsRepositoryFactoryJdbcImpl 2015-11-30 15:16:19,646 INFO [eu.peppol.statistics.RawStatisticsRepositoryFactoryProvider] Since RawStatisticsRepositoryFactoryJdbcImpl is the built-in implementation, we will look for other 3rd party implementaions 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Loading instance of eu.peppol.jdbc.OxalisDataSourceFactory from class path using META-INF/services idiom 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Looking for META-INF/services/eu.peppol.jdbc.OxalisDataSourceFactory in classpath ... 2015-11-30 15:16:19,648 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryProvider] Found it in jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/services/eu.peppol.jdbc.OxalisDataSourceFactory 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] Configuring DataSource wrapped in a Database Connection Pool, using custom loader 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] Loading JDBC Driver with custom class path: file:////opt/tomcat/lib/ojdbc6.jar 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] className=oracle.jdbc.driver.OracleDriver 2015-11-30 15:16:19,651 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] connectURI=jdbc:oracle:thin:@marqadb01.elcom.com:1521:DEV 2015-11-30 15:16:19,652 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] userName=oxalis 2015-11-30 15:16:19,652 DEBUG [eu.peppol.jdbc.OxalisDataSourceFactoryDbcpImpl] password=oxalis 2015-11-30 15:16:19,825 DEBUG [eu.peppol.security.PeppolTrustStore] Loading and combining trust stores: truststore-test.jks 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-root-0 for certificate CN=PEPPOL Root TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-smp-0 for certificate CN=PEPPOL SERVICE METADATA PUBLISHER TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,855 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-ap-0 for certificate CN=PEPPOL ACCESS POINT TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,856 DEBUG [eu.peppol.security.KeyStoreUtil] Adding alias peppol-sts-0 for certificate CN=PEPPOL SECURITY TOKEN SERVICE TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK 2015-11-30 15:16:19,856 DEBUG [eu.peppol.security.KeystoreManager] Loading PEPPOL keystore from /usr/local/keys/oxalis.jks 2015-11-30 15:16:19,875 DEBUG [eu.peppol.outbound.transmission.StartMessageSender] Constructing document body.... 2015-11-30 15:16:19,911 DEBUG [oxalis-out] Constructing SOAP header 2015-11-30 15:16:20,087 DEBUG [oxalis-out] Constructing service proxy 2015-11-30 15:16:20,089 DEBUG [oxalis-out] Found WSDL file at jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/wsdl/wsdl_v2.0.wsdl Nov 30, 2015 3:16:20 PM [com.sun.xml.ws.policy.parser.PolicyConfigParser] parse INFO: WSP5018: Loaded WSIT configuration from file: jar:file:/home/oxalis/bin/oxalis-standalone.jar!/META-INF/wsit-client.xml. 2015-11-30 15:16:20,621 DEBUG [oxalis-out] Getting remote resource binding port 2015-11-30 15:16:21,832 DEBUG [eu.peppol.security.callbacks.TrustStoreCallbackHandler] Initializing the TrustStoreCallbackHandler 2015-11-30 15:16:22,250 DEBUG [oxalis-out] setting connectTimeout 5000 readTimeout 5000 2015-11-30 15:16:22,251 INFO [oxalis-out] Performing SOAP request to: https://peppoldev.elcom.com/oxalis/accessPointService 2015-11-30 15:16:22,276 DEBUG [oxalis-out] SOAP outbound handler called 2015-11-30 15:16:22,277 DEBUG [oxalis-out] Adding BUSDOX headers to SOAP-envelope 2015-11-30 15:16:22,578 DEBUG [eu.peppol.security.callbacks.SAMLCallbackHandler] Requested SAML callback handling 2015-11-30 15:16:22,578 DEBUG [eu.peppol.security.callbacks.SAMLCallbackHandler] Creating and setting the SAML Sender Vouches Assertion 2015-11-30 15:16:23,107 DEBUG [eu.peppol.security.callbacks.KeyStoreCallbackHandler] Keystore callback handler: returning keystore Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultCallbackHandler getDefaultPrivKeyCert SEVERE: WSS1512: An Error occurred while locating default certificate and private key in KeyStore. Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultCallbackHandler getDefaultPrivKeyCert SEVERE: WSS1505: IO Exception occured: failed to get key/certificate from KeyStore (not necessarily i/o exception) java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138)

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl getDefaultPrivKeyCertRequest SEVERE: WSS0216: An Error occurred using CallbackHandler for : SignatureKeyCallback.DefaultPrivKeyCertRequest Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl getDefaultPrivKeyCertRequest SEVERE: WSS0217: An Error occurred using CallbackHandler handle() Method. java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.impl.filter.SignatureFilter process SEVERE: WSS1417: Error while processing signature [Ljava.lang.Object;@37900c04 Nov 30, 2015 3:16:23 PM com.sun.xml.wss.jaxws.impl.SecurityTubeBase secureOutboundMessage SEVERE: WSSTUBE0024: Error in Securing Outbound Message. com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM com.sun.xml.wss.jaxws.impl.SecurityClientTube processClientRequestPacket SEVERE: WSSTUBE0024: Error in Securing Outbound Message. com.sun.xml.wss.impl.WssSoapFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.SecurableSoapMessage.newSOAPFaultException(SecurableSoapMessage.java:349) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:393) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) ... 28 more Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Nov 30, 2015 3:16:23 PM [com.sun.xml.ws.rx.rm.runtime.ClientTube] doRun SEVERE: WSRM1106: Attempt to send a [ CreateSequence ] RM session control message failed with an exception javax.xml.ws.WebServiceException: WSSTUBE0024: Error in Securing Outbound Message. at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:328) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processRequest(SecurityClientTube.java:247) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.rx.util.FiberExecutor.runSync(FiberExecutor.java:94) at com.sun.xml.ws.rx.util.Communicator.send(Communicator.java:423) at com.sun.xml.ws.rx.rm.runtime.ClientTube.sendSessionControlMessage(ClientTube.java:555) at com.sun.xml.ws.rx.rm.runtime.ClientTube.createSequences(ClientTube.java:415) at com.sun.xml.ws.rx.rm.runtime.ClientTube.openRmSession(ClientTube.java:338) at com.sun.xml.ws.rx.rm.runtime.ClientTube.processRequest(ClientTube.java:179) at com.sun.xml.ws.api.pipe.Fiber.__doRun(Fiber.java:1063) at com.sun.xml.ws.api.pipe.Fiber._doRun(Fiber.java:979) at com.sun.xml.ws.api.pipe.Fiber.doRun(Fiber.java:950) at com.sun.xml.ws.api.pipe.Fiber.runSync(Fiber.java:825) at com.sun.xml.ws.client.Stub.process(Stub.java:443) at com.sun.xml.ws.client.sei.SEIStub.doProcess(SEIStub.java:174) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:119) at com.sun.xml.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:102) at com.sun.xml.ws.client.sei.SEIStub.invoke(SEIStub.java:154) at com.sun.proxy.$Proxy50.create(Unknown Source) at eu.peppol.outbound.soap.SoapDispatcher.sendSoapMessage(SoapDispatcher.java:301) at eu.peppol.outbound.soap.SoapDispatcher.send(SoapDispatcher.java:238) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:99) at eu.peppol.outbound.transmission.StartMessageSender.send(StartMessageSender.java:46) at eu.peppol.outbound.transmission.Transmitter.transmit(Transmitter.java:48) at eu.sendregning.oxalis.Main.main(Main.java:138) Caused by: javax.xml.ws.soap.SOAPFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.getSOAPFaultException(SecurityTubeBase.java:715) ... 28 more Caused by: com.sun.xml.wss.impl.WssSoapFaultException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.SecurableSoapMessage.newSOAPFaultException(SecurableSoapMessage.java:349) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:393) at com.sun.xml.wss.jaxws.impl.SecurityClientTube.processClientRequestPacket(SecurityClientTube.java:323) ... 27 more Caused by: com.sun.xml.wss.XWSSecurityException: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:321) at com.sun.xml.wss.impl.HarnessUtil.processWSSPolicy(HarnessUtil.java:99) at com.sun.xml.wss.impl.HarnessUtil.processDeep(HarnessUtil.java:278) at com.sun.xml.wss.impl.SecurityAnnotator.processMessagePolicy(SecurityAnnotator.java:194) at com.sun.xml.wss.impl.SecurityAnnotator.secureMessage(SecurityAnnotator.java:155) at com.sun.xml.wss.jaxws.impl.SecurityTubeBase.secureOutboundMessage(SecurityTubeBase.java:388) ... 28 more Caused by: com.sun.xml.wss.XWSSecurityException: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:266) at com.sun.xml.wss.impl.filter.SignatureFilter.process(SignatureFilter.java:235) ... 33 more Caused by: java.lang.RuntimeException: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1474) at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.handle(DefaultCallbackHandler.java:553) at com.sun.xml.wss.impl.misc.DefaultSecurityEnvironmentImpl.getDefaultPrivKeyCertRequest(DefaultSecurityEnvironmentImpl.java:261) ... 34 more Caused by: java.lang.RuntimeException: An Error occurred while locating default certificate and privateKey in KeyStore at com.sun.xml.wss.impl.misc.DefaultCallbackHandler.getDefaultPrivKeyCert(DefaultCallbackHandler.java:1469) ... 36 more

Message failed : WSRM1106: Attempt to send a [ CreateSequence ] RM session control message failed with an exception

— Reply to this email directly or view it on GitHub https://github.com/difi/oxalis/issues/242.

Steinar Overbeck Cook

ghost commented 9 years ago

Thanks for the response Steinarcok.

We followed steps from https://github.com/difi/oxalis/blob/master/doc/keystore.md to import the peppol certificates. keytool -importkeystore -srckeystore oxalis-keystore.p12 -srcstoretype PKCS12 -srcstorepass ${password} \ -alias ${aliasname} -destkeystore oxalis-keystore.jks -deststorepass peppol

Oxalis has any specific test to validate the installation of certs?

Here is snapshot of cert what we have, do you see any issue? :

$:/keytool -list -v -keystore /usr/local/keys/oxalis.jks Enter keystore password:

Keystore type: JKS Keystore provider: SUN

Your keystore contains 4 entries

Alias name: XXXXXX Creation date: Nov 30, 2015 Entry type: PrivateKeyEntry Certificate chain length: 1 Certificate[1]: Owner: CN=APP_1000000162, O=XXXXXXXXX, C=UK Issuer: CN=PEPPOL ACCESS POINT TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK Serial number: XXXXXXXXXXXXXXXXXXXXXXXXXXXXX Valid from: Wed Nov 18 19:00:00 EST 2015 until: Sat Nov 18 18:59:59 EST 2017 Certificate fingerprints: MD5: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX SHA1: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX SHA256: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX Signature algorithm name: SHA256withRSA Version: 3

steinarcook commented 9 years ago

There should only be a single entry in your keystore, but you have 4.

Steinar Overbeck Cook +47 90665793

Den 3. des. 2015 kl. 13.43 skrev ahmedinweb notifications@github.com:

Thanks for the response Steinarcok.

We followed steps from https://github.com/difi/oxalis/blob/master/doc/keystore.md to import the peppol certificates. keytool -importkeystore -srckeystore oxalis-keystore.p12 -srcstoretype PKCS12 -srcstorepass ${password} \ -alias ${aliasname} -destkeystore oxalis-keystore.jks -deststorepass peppol

Oxalis has any specific test to validate the installation of certs?

Here is snapshot of cert what we have, do you see any issue? :

$:/keytool -list -v -keystore /usr/local/keys/oxalis.jks Enter keystore password:

Keystore type: JKS Keystore provider: SUN

Your keystore contains 4 entries

Alias name: XXXXXX Creation date: Nov 30, 2015 Entry type: PrivateKeyEntry Certificate chain length: 1 Certificate[1]: Owner: CN=APP_1000000162, O=XXXXXXXXX, C=UK Issuer: CN=PEPPOL ACCESS POINT TEST CA, OU=FOR TEST PURPOSES ONLY, O=NATIONAL IT AND TELECOM AGENCY, C=DK Serial number: XXXXXXXXXXXXXXXXXXXXXXXXXXXXX Valid from: Wed Nov 18 19:00:00 EST 2015 until: Sat Nov 18 18:59:59 EST 2017 Certificate fingerprints: MD5: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX SHA1: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX SHA256: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX Signature algorithm name: SHA256withRSA Version: 3

— Reply to this email directly or view it on GitHub.

ghost commented 9 years ago

Hi Steinarcook, Thanks once again for looking in to the issue. Much appreciated. I think, there are some basic steps we are missing while creating keystore.

Little background of what we have in our system. Yes, we have 4 entries. Entry 1: keystore itself Entry 2: AP Entry 3: SMP Entry 4: our org cert.

Based on your comments if the keystore has to have only one entry, then the way we are creating keystore is wrong. If we create a keystore only for AP, in this case we will have two entries in keystore (AP and keysote itself). The below commands used for creating keystore.

Create the keystore oxalis-ap.jks: keytool -keystore oxalis-ap.jks -genkey -alias oxalis_ap

Create the peppol-ap PKCS keystore: openssl pkcs12 -export -in peppol-ap.crt -inkey ap-private.key -out peppol_ap-keystore.p12 -passout pass:XXXXXXXXXX -name peppol_ap

Import the peppol-ap PKCS keystore in to the oxalis-ap.jks keystore: keytool -importkeystore -srckeystore peppol_ap-keystore.p12 -srcstoretype PKCS12 -srcstorepass XXXXXX -alias peppol_ap -destkeystore oxalis-ap.jks -deststorepass XXXXXXX

keytool -list -v -keystore oxalis-ap.jks display two entries (ap, keyitself).

Can you guide, how to create keystore with single entry? Rest after hearing from you. Thanks once again for your help.

steinarcook commented 9 years ago

All of the PEPPOL certificates are included in Oxalis.

You keystore should only contain a single entry with your private key and your certificate.

Please consult the documentation for further details.

Steinar Overbeck Cook +47 90665793

Den 4. des. 2015 kl. 15.44 skrev ahmedinweb notifications@github.com:

Hi Steinarcook, Thanks once again for looking in to the issue. Much appreciated. I think, there are some basic steps we are missing while creating keystore.

Little background of what we have in our system. Yes, we have 4 entries. Entry 1: keystore itself Entry 2: AP Entry 3: SMP Entry 4: our org cert.

Based on your comments if the keystore has to have only one entry, then the way we are creating keystore is wrong. If we create a keystore only for AP, in this case we will have two entries in keystore (AP and keysote itself). The below commands used for creating keystore.

Create the keystore oxalis-ap.jks: keytool -keystore oxalis-ap.jks -genkey -alias oxalis_ap

Create the peppol-ap PKCS keystore: openssl pkcs12 -export -in peppol-ap.crt -inkey ap-private.key -out peppol_ap-keystore.p12 -passout pass:XXXXXXXXXX -name peppol_ap

Import the peppol-ap PKCS keystore in to the oxalis-ap.jks keystore: keytool -importkeystore -srckeystore peppol_ap-keystore.p12 -srcstoretype PKCS12 -srcstorepass XXXXXX -alias peppol_ap -destkeystore oxalis-ap.jks -deststorepass XXXXXXX

keytool -list -v -keystore oxalis-ap.jks display two entries (ap, keyitself).

Can you guide, how to create keystore with single entry? Rest after hearing from you. Thanks once again for your help.

— Reply to this email directly or view it on GitHub.

ghost commented 9 years ago

I am looking at documentation for keystore: https://github.com/difi/oxalis/blob/master/doc/keystore.md Can you point me to correct place look for? It is not clear how we get all certs in one entry. Thanks.

steinarcook commented 9 years ago

The keystore should contain only your private key and the corresponding certificate.

The root and intermediate certificates are included in the Oxalis distribution and must not be included in your keystore.

Steinar Overbeck Cook +47 90665793

Den 4. des. 2015 kl. 18.19 skrev ahmedinweb notifications@github.com:

I am looking at documentation for keystore: https://github.com/difi/oxalis/blob/master/doc/keystore.md Can you point me to correct place look for? It is not clear how we get all certs in one entry. Thanks.

— Reply to this email directly or view it on GitHub.

steinarcook commented 9 years ago

Please see https://github.com/difi/oxalis/blob/master/doc/keystore.md "How do I obtain a PEPPOL certificate for my Access point?", items 5 through 7.