Hi everyone,
I'm asking this question in English since German is not my primary language. I'm interested in the HBCI protocol and I'm currently trying to create a setup where I can run the demo attached with the hbci4java source code. I have performed the following steps: first, I compiled the hbci4java source code. Next, I followed the instructions on the Github repository of hbci4javaserver (https://github.com/thomet/hbci4javaserver). My server is running and I can access the admin panel where I can add and modify users.
Next, I created a passport using the InitAndTest tool. I have used the following parameters when creating the passport: https://gist.github.com/devos50/e4fa4c8707fa10475548. In summary, I'm trying to create a PinTan passport for the user 'nutzer1' using HBCI+. The biz, host and port corresponds to the configurations of the hbci4javaserver I have setup in the previous step. Finally, I adjusted the hbci4java.properties file to match the location of my passport (see https://gist.github.com/devos50/08d016dec22eb0175cbf).
Now, when I run the demo (by running 'bash runjobs.sh' in the background), I get the following error in the log of the server:
The content of jobs.log is posted below:
Apparently, the needtan property is incorrect and it should be 'J' or 'N' but I'm not sure where I can specify this user-defined property. Or is there something else that is causing the error?
Any help would be greatly appreciated. Thanks in advance
I'm asking this question in English since German is not my primary language. I'm interested in the HBCI protocol and I'm currently trying to create a setup where I can run the demo attached with the hbci4java source code. I have performed the following steps: first, I compiled the hbci4java source code. Next, I followed the instructions on the Github repository of hbci4javaserver (https://github.com/thomet/hbci4javaserver). My server is running and I can access the admin panel where I can add and modify users.
Next, I created a passport using the InitAndTest tool. I have used the following parameters when creating the passport: https://gist.github.com/devos50/e4fa4c8707fa10475548. In summary, I'm trying to create a PinTan passport for the user 'nutzer1' using HBCI+. The biz, host and port corresponds to the configurations of the hbci4javaserver I have setup in the previous step. Finally, I adjusted the hbci4java.properties file to match the location of my passport (see https://gist.github.com/devos50/08d016dec22eb0175cbf).
Now, when I run the demo (by running 'bash runjobs.sh' in the background), I get the following error in the log of the server:
Code
<WRN> [2015.10.06 17:47:36.537] [RMI Runtime/RMI TCP Connection(25)-10.0.1.129] protocol.MSG: could not insert the following user-defined data into message: DialogInitAnonRes.BPD.Params_6.KUmsZeitPar4.ParKUmsZeit.canallaccounts=J
<ERR> [2015.10.06 17:47:36.541] [RMI Runtime/RMI TCP Connection(25)-10.0.1.129] manager.HBCIUtils: HBCI4Java Exception BEGIN ---error while initializing element DialogInitAnonRes.BPD.Params_18.PinTanPar1.ParPinTan.PinTanGV.needtan
-> invalid argument: value must be J or N
HBCI4Java Exception END ---
HBCI4Java stacktrace BEGIN ---org.kapott.hbci.exceptions.InitializingException: error while initializing element DialogInitAnonRes.BPD.Params_18.PinTanPar1.ParPinTan.PinTanGV.needtan
at org.kapott.hbci.datatypes.factory.SyntaxDEFactory.createSyntaxDE(SyntaxDEFactory.java:92)
at org.kapott.hbci.protocol.DE.setValue(DE.java:183)
at org.kapott.hbci.protocol.DE.propagateValue(DE.java:79)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MSG.propagateUserData(MSG.java:78)
at org.kapott.hbci.protocol.MSG.initData(MSG.java:127)
at org.kapott.hbci.protocol.MSG.init(MSG.java:122)
at org.kapott.hbci.protocol.factory.MSGFactory.createMSG(MSGFactory.java:61)
at org.kapott.hbci.manager.MsgGen.generate(MsgGen.java:107)
at org.kapott.hbci.server.msg.AbstractMsgHandler.createResponseMessage(AbstractMsgHandler.java:396)
at org.kapott.hbci.server.msg.AbstractMsgHandler.handleMessage(AbstractMsgHandler.java:128)
at org.kapott.hbci.server.Dialog.handleMessage(Dialog.java:200)
at org.kapott.hbci.server.DialogMgr.handleMessage(DialogMgr.java:76)
at org.kapott.hbci.server.listener.RMIListenerImpl.handleMessage(RMIListenerImpl.java:58)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
at sun.rmi.transport.Transport$1.run(Transport.java:177)
at sun.rmi.transport.Transport$1.run(Transport.java:174)
at java.security.AccessController.doPrivileged(Native Method)
at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:556)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:811)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:670)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.kapott.hbci.exceptions.InvalidArgumentException: invalid argument: value must be J or N
at org.kapott.hbci.datatypes.SyntaxJN.check(SyntaxJN.java:35)
at org.kapott.hbci.datatypes.SyntaxJN.<init>(SyntaxJN.java:41)
at sun.reflect.GeneratedConstructorAccessor8.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at org.kapott.hbci.datatypes.factory.SyntaxDEFactory.createSyntaxDE(SyntaxDEFactory.java:88)
... 39 more
HBCI4Java stacktrace END ---
<ERR> [2015.10.06 17:47:36.541] [RMI Runtime/RMI TCP Connection(25)-10.0.1.129] manager.HBCIUtils: HBCI4Java Exception BEGIN ---error while initializing element DialogInitAnonRes.BPD.Params_18.PinTanPar1.ParPinTan.PinTanGV.needtan
-> invalid argument: value must be J or N
HBCI4Java Exception END ---
HBCI4Java stacktrace BEGIN ---org.kapott.hbci.exceptions.InitializingException: error while initializing element DialogInitAnonRes.BPD.Params_18.PinTanPar1.ParPinTan.PinTanGV.needtan
at org.kapott.hbci.datatypes.factory.SyntaxDEFactory.createSyntaxDE(SyntaxDEFactory.java:92)
at org.kapott.hbci.protocol.DE.setValue(DE.java:183)
at org.kapott.hbci.protocol.DE.propagateValue(DE.java:79)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MultipleSyntaxElements.propagateValue(MultipleSyntaxElements.java:185)
at org.kapott.hbci.protocol.SyntaxElement.propagateValue(SyntaxElement.java:467)
at org.kapott.hbci.protocol.MSG.propagateUserData(MSG.java:78)
at org.kapott.hbci.protocol.MSG.initData(MSG.java:127)
at org.kapott.hbci.protocol.MSG.init(MSG.java:122)
at org.kapott.hbci.protocol.factory.MSGFactory.createMSG(MSGFactory.java:61)
at org.kapott.hbci.manager.MsgGen.generate(MsgGen.java:107)
at org.kapott.hbci.server.msg.AbstractMsgHandler.createResponseMessage(AbstractMsgHandler.java:396)
at org.kapott.hbci.server.msg.AbstractMsgHandler.handleMessage(AbstractMsgHandler.java:128)
at org.kapott.hbci.server.Dialog.handleMessage(Dialog.java:200)
at org.kapott.hbci.server.DialogMgr.handleMessage(DialogMgr.java:76)
at org.kapott.hbci.server.listener.RMIListenerImpl.handleMessage(RMIListenerImpl.java:58)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:322)
at sun.rmi.transport.Transport$1.run(Transport.java:177)
at sun.rmi.transport.Transport$1.run(Transport.java:174)
at java.security.AccessController.doPrivileged(Native Method)
at sun.rmi.transport.Transport.serviceCall(Transport.java:173)
at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:556)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:811)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:670)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.kapott.hbci.exceptions.InvalidArgumentException: invalid argument: value must be J or N
at org.kapott.hbci.datatypes.SyntaxJN.check(SyntaxJN.java:35)
at org.kapott.hbci.datatypes.SyntaxJN.<init>(SyntaxJN.java:41)
at sun.reflect.GeneratedConstructorAccessor8.newInstance(Unknown Source)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at org.kapott.hbci.datatypes.factory.SyntaxDEFactory.createSyntaxDE(SyntaxDEFactory.java:88)
... 39 more
HBCI4Java stacktrace END ---
The content of jobs.log is posted below:
Code
<DBG> [2015.10.06 19:44:55.881] [main/main] manager.HBCIUtils: using default system locale nl_NL
<DBG> [2015.10.06 19:44:55.898] [main/main] manager.HBCIUtils: initialized HBCI4Java for thread group main
<INF> [2015.10.06 19:44:55.899] [main/main] manager.HBCIUtils: This is HBCI4Java-2.5.12
<DBG> [2015.10.06 19:44:55.899] [main/main] manager.HBCIUtils: trying to load BLZ data
<DBG> [2015.10.06 19:44:55.960] [main/main] passport.AbstractHBCIPassport: creating new instance of a PinTan passport
<DBG> [2015.10.06 19:44:55.966] [main/main] passport.HBCIPassportPinTan: loading passport data from file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:55.966] [main/main] passport.HBCIPassportPinTan: loading data from file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:56.665] [main/main] manager.HBCIHandler: registering institute
<INF> [2015.10.06 19:44:56.667] [main/main] manager.HBCIInstitute: fetching BPD
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: creating new raw message DialogInitAnon
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.Idn.KIK.blz to "12345678"
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.Idn.KIK.country to "DE"
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.BPD to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.UPD to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.lang to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.prodName to "HBCI4Java"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.prodVersion to "2.5"
<DBG> [2015.10.06 19:44:56.670] [main/main] manager.HBCIKernelImpl: generating raw message DialogInitAnon
<DBG> [2015.10.06 19:44:56.701] [main/main] protocol.MSG: user data: {DialogInitAnon.ProcPrep.UPD=0, DialogInitAnon.Idn.KIK.blz=12345678, DialogInitAnon.ProcPrep.prodVersion=2.5, DialogInitAnon.ProcPrep.lang=0, DialogInitAnon.ProcPrep.prodName=HBCI4Java, DialogInitAnon.ProcPrep.BPD=0, DialogInitAnon.Idn.KIK.country=DE}
<DB2> [2015.10.06 19:44:56.712] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000111+220+0+1'HKIDN:2:2+280:12345678+9999999999+0+0'HKVVB:3:2+0+0+0+HBCI4Java+2.5'HNHBS:4:1+1'
<DBG> [2015.10.06 19:44:56.713] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 0/1
<DBG> [2015.10.06 19:44:56.715] [main/main] comm.FilterBase64: using filter: MIM (base64)
<INF> [2015.10.06 19:44:56.715] [main/main] comm.CommPinTan: creating a connection to https://127.0.0.1:8443/pintan/PinTanServlet and checking the certificate
<WRN> [2015.10.06 19:44:56.717] [main/main] comm.PinTanSSLSocketFactory: creating socket factory with disabled cert checking
<DBG> [2015.10.06 19:44:56.720] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:56.782] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:56.782] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:56.784] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:56.839] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> server cert always OK
<DBG> [2015.10.06 19:44:56.839] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> will return 'accepted issuers'
<DBG> [2015.10.06 19:44:56.854] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:56.855] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:56.856] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:56.856] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:56.950] [main/main] comm.CommPinTan: found messagesize: 240
<DB2> [2015.10.06 19:44:56.950] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: we still need 239 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: received 239 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:56.952] [main/main] comm.Comm: received message: HNHBK:1:3+000000000179+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:56.952] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:56.955] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as DialogInitAnonRes message
<DB2> [2015.10.06 19:44:57.005] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000179+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.006] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.006] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<DBG> [2015.10.06 19:44:57.006] [main/main] manager.HBCIKernelImpl: looking for a signature
<DBG> [2015.10.06 19:44:57.008] [main/main] security.Sig: message does not need a signature
<DBG> [2015.10.06 19:44:57.008] [main/main] manager.HBCIKernelImpl: looking if message is encrypted
<DBG> [2015.10.06 19:44:57.008] [main/main] passport.AbstractPinTanPassport: dialog init ended with errors - searching for return code 'wrong PIN'
<DBG> [2015.10.06 19:44:57.008] [main/main] passport.AbstractPinTanPassport: autosecfunc: search for 3920s in response to detect allowed twostep secmechs
<DBG> [2015.10.06 19:44:57.009] [main/main] manager.HBCIInstitute: extracting BPD from results
<DBG> [2015.10.06 19:44:57.014] [main/main] passport.HBCIPassportPinTan: writing to passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat_7140067065171574008
<DBG> [2015.10.06 19:44:57.015] [main/main] passport.HBCIPassportPinTan: saving two step mechs: []
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: saving current tan method: 999
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: closing output stream
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: deleting old passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: renaming my_passport_pintan.dat_7140067065171574008 to my_passport_pintan.dat
<DB2> [2015.10.06 19:44:57.016] [main/main] manager.HBCIKernelImpl: creating new raw message DialogEndAnon
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgHead.dialogid to "207556572612533274"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgHead.msgnum to "2"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.DialogEndS.dialogid to "207556572612533274"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgTail.msgnum to "2"
<DBG> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: generating raw message DialogEndAnon
<DBG> [2015.10.06 19:44:57.018] [main/main] protocol.MSG: user data: {DialogEndAnon.MsgTail.msgnum=2, DialogEndAnon.DialogEndS.dialogid=207556572612533274, DialogEndAnon.MsgHead.msgnum=2, DialogEndAnon.MsgHead.dialogid=207556572612533274}
<DB2> [2015.10.06 19:44:57.019] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000089+220+207556572612533274+2'HKEND:2:1+207556572612533274'HNHBS:3:1+2'
<DBG> [2015.10.06 19:44:57.019] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 207556572612533274/2
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:57.020] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:57.027] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: found messagesize: 220
<DB2> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: we still need 219 bytes
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: received 219 bytes
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.Comm: received message: HNHBK:1:3+000000000164+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.HBCI_Exception?: invalid message number'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.049] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:57.049] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as DialogEndAnonRes message
<DB2> [2015.10.06 19:44:57.055] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000164+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.HBCI_Exception?: invalid message number'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.055] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.055] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.HBCI_Exception: invalid message number
<ERR> [2015.10.06 19:44:57.056] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.HBCI_Exception: received message number (1) in message header does not match sent message number (2)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:419)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:178)
at org.kapott.hbci.manager.HBCIInstitute.doDialogEnd(HBCIInstitute.java:189)
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:328)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<ERR> [2015.10.06 19:44:57.056] [main/main] manager.HBCIInstitute: dialog end failed: org.kapott.hbci.exceptions.HBCI_Exception: received message number (1) in message header does not match sent message number (2)
9800:org.kapott.hbci.exceptions.HBCI_Exception: invalid message number
<ERR> [2015.10.06 19:44:57.057] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.ProcessException: dialog end failed
at org.kapott.hbci.manager.HBCIInstitute.doDialogEnd(HBCIInstitute.java:198)
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:328)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<ERR> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: fetching BPD failed: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.ProcessException: fetching BPD failed
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:335)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: FAILED! - maybe this institute does not support anonymous logins
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: we will nevertheless go on
<DBG> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: checking if requested hbci parameters are supported
<WRN> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: can not check if requested parameters are supported
<DBG> [2015.10.06 19:44:57.057] [main/main] manager.HBCIHandler: registering user
<INF> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: fetching new sys-id from institute
<DBG> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: checking whether passport is supported (but ignoring result)
<DBG> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: passport supported: true
<DB2> [2015.10.06 19:44:57.060] [main/main] manager.HBCIKernelImpl: creating new raw message Synch
<DB2> [2015.10.06 19:44:57.060] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.KIK.blz to "12345678"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.KIK.country to "DE"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.customerid to "XXXXXXX"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.sysid to "0"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.sysStatus to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgHead.dialogid to "0"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgHead.msgnum to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgTail.msgnum to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.BPD to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.UPD to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.lang to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.prodName to "HBCI4Java"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.prodVersion to "2.5"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Sync.mode to "0"
<DBG> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: generating raw message Synch
<DBG> [2015.10.06 19:44:57.066] [main/main] protocol.MSG: user data: {Synch.ProcPrep.lang=0, Synch.Idn.customerid=XXXXXXX, Synch.Idn.KIK.country=DE, Synch.Sync.mode=0, Synch.ProcPrep.BPD=0, Synch.MsgHead.dialogid=0, Synch.MsgHead.msgnum=1, Synch.Idn.sysStatus=1, Synch.ProcPrep.UPD=0, Synch.ProcPrep.prodName=HBCI4Java, Synch.Idn.sysid=0, Synch.MsgTail.msgnum=1, Synch.ProcPrep.prodVersion=2.5, Synch.Idn.KIK.blz=12345678}
<DBG> [2015.10.06 19:44:57.068] [main/main] manager.HBCIKernelImpl: trying to insert signature
<DBG> [2015.10.06 19:44:57.069] [main/main] security.Sig: setting secfunc to 999
<DBG> [2015.10.06 19:44:57.069] [main/main] security.Sig: setting cid to
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting role to 1
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting range to 1
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyblz to 12345678
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keycountry to DE
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyuserid to XXXXXXX
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keynum to 0
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyversion to 0
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting sysid to 0
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigid to 1
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigalg to 10
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigmode to 16
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting hashalg to 999
<DBG> [2015.10.06 19:44:57.075] [main/main] passport.HBCIPassportPinTan: writing to passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat_7790155181956074736
<DBG> [2015.10.06 19:44:57.075] [main/main] passport.HBCIPassportPinTan: saving two step mechs: []
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: saving current tan method: 999
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: closing output stream
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: deleting old passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: renaming my_passport_pintan.dat_7790155181956074736 to my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.079] [main/main] passport.HBCIPassportPinTan: onestep method - checking GVs to decide whether or not we need a TAN
<WRN> [2015.10.06 19:44:57.079] [main/main] passport.HBCIPassportPinTan: the job with the code HNSHK seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKIDN seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKVVB seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKSYN seems not to be allowed with PIN/TAN
<DB2> [2015.10.06 19:44:57.082] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000245+220+0+1'HNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX'HNHBS:7:1+1'
<DBG> [2015.10.06 19:44:57.082] [main/main] manager.HBCIKernelImpl: trying to encrypt message
<DBG> [2015.10.06 19:44:57.083] [main/main] security.Crypt: setting secfunc to 998
<DBG> [2015.10.06 19:44:57.083] [main/main] security.Crypt: setting keytype to 5
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting blz to XXXXX678
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting country to DE
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keyuserid to XXXXXXX
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keynum to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keyversion to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting cid to
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting sysId to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting role to 1
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting alg to 13
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting mode to 2
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting compfunc to 0
<DBG> [2015.10.06 19:44:57.087] [main/main] protocol.MSG: user data: {Synch.ProcPrep.lang=0, Crypted.CryptHead.KeyName.KIK.blz=XXXXX678, Crypted.CryptData.data=BHNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX', Crypted.CryptHead.SecProfile.method=PIN, Synch.Sync.mode=0, Crypted.CryptHead.secfunc=998, Synch.MsgTail.msgnum=1, Crypted.MsgTail.msgnum=1, Synch.Idn.KIK.blz=XXXXX678, Crypted.CryptHead.CryptAlg.keytype=5, Crypted.CryptHead.role=1, Synch.Idn.sysStatus=1, Synch.ProcPrep.UPD=0, Synch.ProcPrep.prodName=HBCI4Java, Crypted.CryptHead.CryptAlg.enckey=B\00\00\00\00\00\00\00\00, Crypted.CryptHead.KeyName.userid=XXXXXXX, Crypted.CryptHead.SecIdnDetails.sysid=0, Crypted.CryptHead.SecIdnDetails.func=1, Crypted.CryptHead.CryptAlg.alg=13, Synch.ProcPrep.BPD=0, Crypted.CryptHead.CryptAlg.mode=2, Crypted.CryptHead.KeyName.KIK.country=DE, Synch.MsgHead.dialogid=0, Synch.Idn.customerid=XXXXXXX, Crypted.CryptHead.KeyName.keyversion=0, Crypted.CryptHead.SecProfile.version=1, Crypted.CryptHead.SecTimestamp.time=19:44:57, Synch.MsgHead.msgnum=1, Crypted.CryptHead.SecTimestamp.date=2015-10-06, Crypted.CryptHead.KeyName.keynum=0, Synch.ProcPrep.prodVersion=2.5, Crypted.MsgHead.msgnum=1, Crypted.CryptHead.compfunc=0, Synch.Idn.sysid=0, Crypted.MsgHead.dialogid=0, Synch.Idn.KIK.country=DE}
<WRN> [2015.10.06 19:44:57.088] [main/main] protocol.MSG: could not insert the following user-defined data into message: Crypted.CryptHead.SecProfile.method=PIN
<WRN> [2015.10.06 19:44:57.089] [main/main] protocol.MSG: could not insert the following user-defined data into message: Crypted.CryptHead.SecProfile.version=1
<DB2> [2015.10.06 19:44:57.091] [main/main] manager.HBCIKernelImpl: encrypted message to be sent: HNHBK:1:3+000000000356+220+0+1'HNVSK:998:2+998+1+1::0+1:20151006:194457+2:2:13:@8@\00\00\00\00\00\00\00\00:5:1+280:XXXXX678:XXXXXXX:V:0:0+0'HNVSD:999:1+@202@HNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX''HNHBS:7:1+1'
<DBG> [2015.10.06 19:44:57.091] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 0/1
<DBG> [2015.10.06 19:44:57.091] [main/main] comm.FilterBase64: using filter: MIM (base64)
<INF> [2015.10.06 19:44:57.091] [main/main] comm.CommPinTan: creating a connection to https://127.0.0.1:8443/pintan/PinTanServlet and checking the certificate
<WRN> [2015.10.06 19:44:57.091] [main/main] comm.PinTanSSLSocketFactory: creating socket factory with disabled cert checking
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:57.092] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:57.102] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> server cert always OK
<DBG> [2015.10.06 19:44:57.103] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> will return 'accepted issuers'
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: found messagesize: 240
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: we still need 239 bytes
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: received 239 bytes
<DB2> [2015.10.06 19:44:57.183] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:57.183] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:57.183] [main/main] comm.Comm: received message: HNHBK:1:3+000000000179+220+321825383074345556+1+321825383074345556:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.183] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:57.184] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as SynchRes message
<DB2> [2015.10.06 19:44:57.194] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000179+220+321825383074345556+1+321825383074345556:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.194] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.195] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<DBG> [2015.10.06 19:44:57.195] [main/main] manager.HBCIKernelImpl: looking for a signature
<WRN> [2015.10.06 19:44:57.195] [main/main] security.Sig: message has no signature
<DBG> [2015.10.06 19:44:57.195] [main/main] manager.HBCIKernelImpl: looking if message is encrypted
<ERR> [2015.10.06 19:44:57.195] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.HBCI_Exception: message is expected to be encrypted
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:454)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:178)
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:442)
at org.kapott.hbci.manager.HBCIUser.updateUserData(HBCIUser.java:665)
at org.kapott.hbci.manager.HBCIUser.register(HBCIUser.java:690)
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:279)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:133)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<DBG> [2015.10.06 19:44:57.195] [main/main] passport.AbstractPinTanPassport: dialog init ended with errors - searching for return code 'wrong PIN'
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: search for 3920s in response to detect allowed twostep secmechs
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: (re)checking selected pintan secmech
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: there is only one pintan method (999) supported - choosing this automatically
Exception in thread "main" org.kapott.hbci.exceptions.HBCI_Exception: can not create HBCI handle
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:142)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: can not register user
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:281)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:133)
... 1 more
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: error while fetching new sysid from institute
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:474)
at org.kapott.hbci.manager.HBCIUser.updateUserData(HBCIUser.java:665)
at org.kapott.hbci.manager.HBCIUser.register(HBCIUser.java:690)
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:279)
... 2 more
Caused by: org.kapott.hbci.exceptions.ProcessException: error while fetching new sysid from institute
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:461)
... 5 more
<DBG> [2015.10.06 19:44:55.898] [main/main] manager.HBCIUtils: initialized HBCI4Java for thread group main
<INF> [2015.10.06 19:44:55.899] [main/main] manager.HBCIUtils: This is HBCI4Java-2.5.12
<DBG> [2015.10.06 19:44:55.899] [main/main] manager.HBCIUtils: trying to load BLZ data
<DBG> [2015.10.06 19:44:55.960] [main/main] passport.AbstractHBCIPassport: creating new instance of a PinTan passport
<DBG> [2015.10.06 19:44:55.966] [main/main] passport.HBCIPassportPinTan: loading passport data from file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:55.966] [main/main] passport.HBCIPassportPinTan: loading data from file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:56.665] [main/main] manager.HBCIHandler: registering institute
<INF> [2015.10.06 19:44:56.667] [main/main] manager.HBCIInstitute: fetching BPD
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: creating new raw message DialogInitAnon
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.Idn.KIK.blz to "12345678"
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.Idn.KIK.country to "DE"
<DB2> [2015.10.06 19:44:56.667] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.BPD to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.UPD to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.lang to "0"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.prodName to "HBCI4Java"
<DB2> [2015.10.06 19:44:56.668] [main/main] manager.HBCIKernelImpl: setting raw property DialogInitAnon.ProcPrep.prodVersion to "2.5"
<DBG> [2015.10.06 19:44:56.670] [main/main] manager.HBCIKernelImpl: generating raw message DialogInitAnon
<DBG> [2015.10.06 19:44:56.701] [main/main] protocol.MSG: user data: {DialogInitAnon.ProcPrep.UPD=0, DialogInitAnon.Idn.KIK.blz=12345678, DialogInitAnon.ProcPrep.prodVersion=2.5, DialogInitAnon.ProcPrep.lang=0, DialogInitAnon.ProcPrep.prodName=HBCI4Java, DialogInitAnon.ProcPrep.BPD=0, DialogInitAnon.Idn.KIK.country=DE}
<DB2> [2015.10.06 19:44:56.712] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000111+220+0+1'HKIDN:2:2+280:12345678+9999999999+0+0'HKVVB:3:2+0+0+0+HBCI4Java+2.5'HNHBS:4:1+1'
<DBG> [2015.10.06 19:44:56.713] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 0/1
<DBG> [2015.10.06 19:44:56.715] [main/main] comm.FilterBase64: using filter: MIM (base64)
<INF> [2015.10.06 19:44:56.715] [main/main] comm.CommPinTan: creating a connection to https://127.0.0.1:8443/pintan/PinTanServlet and checking the certificate
<WRN> [2015.10.06 19:44:56.717] [main/main] comm.PinTanSSLSocketFactory: creating socket factory with disabled cert checking
<DBG> [2015.10.06 19:44:56.720] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:56.782] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:56.782] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:56.784] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:56.839] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> server cert always OK
<DBG> [2015.10.06 19:44:56.839] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> will return 'accepted issuers'
<DBG> [2015.10.06 19:44:56.854] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:56.855] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:56.856] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:56.856] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:56.950] [main/main] comm.CommPinTan: found messagesize: 240
<DB2> [2015.10.06 19:44:56.950] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: we still need 239 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: received 239 bytes
<DB2> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:56.951] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:56.952] [main/main] comm.Comm: received message: HNHBK:1:3+000000000179+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:56.952] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:56.955] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as DialogInitAnonRes message
<DB2> [2015.10.06 19:44:57.005] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000179+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.006] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.006] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<DBG> [2015.10.06 19:44:57.006] [main/main] manager.HBCIKernelImpl: looking for a signature
<DBG> [2015.10.06 19:44:57.008] [main/main] security.Sig: message does not need a signature
<DBG> [2015.10.06 19:44:57.008] [main/main] manager.HBCIKernelImpl: looking if message is encrypted
<DBG> [2015.10.06 19:44:57.008] [main/main] passport.AbstractPinTanPassport: dialog init ended with errors - searching for return code 'wrong PIN'
<DBG> [2015.10.06 19:44:57.008] [main/main] passport.AbstractPinTanPassport: autosecfunc: search for 3920s in response to detect allowed twostep secmechs
<DBG> [2015.10.06 19:44:57.009] [main/main] manager.HBCIInstitute: extracting BPD from results
<DBG> [2015.10.06 19:44:57.014] [main/main] passport.HBCIPassportPinTan: writing to passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat_7140067065171574008
<DBG> [2015.10.06 19:44:57.015] [main/main] passport.HBCIPassportPinTan: saving two step mechs: []
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: saving current tan method: 999
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: closing output stream
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: deleting old passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.016] [main/main] passport.HBCIPassportPinTan: renaming my_passport_pintan.dat_7140067065171574008 to my_passport_pintan.dat
<DB2> [2015.10.06 19:44:57.016] [main/main] manager.HBCIKernelImpl: creating new raw message DialogEndAnon
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgHead.dialogid to "207556572612533274"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgHead.msgnum to "2"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.DialogEndS.dialogid to "207556572612533274"
<DB2> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: setting raw property DialogEndAnon.MsgTail.msgnum to "2"
<DBG> [2015.10.06 19:44:57.017] [main/main] manager.HBCIKernelImpl: generating raw message DialogEndAnon
<DBG> [2015.10.06 19:44:57.018] [main/main] protocol.MSG: user data: {DialogEndAnon.MsgTail.msgnum=2, DialogEndAnon.DialogEndS.dialogid=207556572612533274, DialogEndAnon.MsgHead.msgnum=2, DialogEndAnon.MsgHead.dialogid=207556572612533274}
<DB2> [2015.10.06 19:44:57.019] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000089+220+207556572612533274+2'HKEND:2:1+207556572612533274'HNHBS:3:1+2'
<DBG> [2015.10.06 19:44:57.019] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 207556572612533274/2
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:57.020] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:57.020] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:57.026] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:57.027] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: found messagesize: 220
<DB2> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:57.047] [main/main] comm.CommPinTan: we still need 219 bytes
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: received 219 bytes
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:57.048] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:57.048] [main/main] comm.Comm: received message: HNHBK:1:3+000000000164+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.HBCI_Exception?: invalid message number'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.049] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:57.049] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as DialogEndAnonRes message
<DB2> [2015.10.06 19:44:57.055] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000164+220+207556572612533274+1+207556572612533274:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.HBCI_Exception?: invalid message number'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.055] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.055] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.HBCI_Exception: invalid message number
<ERR> [2015.10.06 19:44:57.056] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.HBCI_Exception: received message number (1) in message header does not match sent message number (2)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:419)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:178)
at org.kapott.hbci.manager.HBCIInstitute.doDialogEnd(HBCIInstitute.java:189)
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:328)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<ERR> [2015.10.06 19:44:57.056] [main/main] manager.HBCIInstitute: dialog end failed: org.kapott.hbci.exceptions.HBCI_Exception: received message number (1) in message header does not match sent message number (2)
9800:org.kapott.hbci.exceptions.HBCI_Exception: invalid message number
<ERR> [2015.10.06 19:44:57.057] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.ProcessException: dialog end failed
at org.kapott.hbci.manager.HBCIInstitute.doDialogEnd(HBCIInstitute.java:198)
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:328)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<ERR> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: fetching BPD failed: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.ProcessException: fetching BPD failed
at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:335)
at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:451)
at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:268)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: FAILED! - maybe this institute does not support anonymous logins
<INF> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: we will nevertheless go on
<DBG> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: checking if requested hbci parameters are supported
<WRN> [2015.10.06 19:44:57.057] [main/main] manager.HBCIInstitute: can not check if requested parameters are supported
<DBG> [2015.10.06 19:44:57.057] [main/main] manager.HBCIHandler: registering user
<INF> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: fetching new sys-id from institute
<DBG> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: checking whether passport is supported (but ignoring result)
<DBG> [2015.10.06 19:44:57.060] [main/main] manager.HBCIUser: passport supported: true
<DB2> [2015.10.06 19:44:57.060] [main/main] manager.HBCIKernelImpl: creating new raw message Synch
<DB2> [2015.10.06 19:44:57.060] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.KIK.blz to "12345678"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.KIK.country to "DE"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.customerid to "XXXXXXX"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.sysid to "0"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Idn.sysStatus to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgHead.dialogid to "0"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgHead.msgnum to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.MsgTail.msgnum to "1"
<DB2> [2015.10.06 19:44:57.061] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.BPD to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.UPD to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.lang to "0"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.prodName to "HBCI4Java"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.ProcPrep.prodVersion to "2.5"
<DB2> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: setting raw property Synch.Sync.mode to "0"
<DBG> [2015.10.06 19:44:57.062] [main/main] manager.HBCIKernelImpl: generating raw message Synch
<DBG> [2015.10.06 19:44:57.066] [main/main] protocol.MSG: user data: {Synch.ProcPrep.lang=0, Synch.Idn.customerid=XXXXXXX, Synch.Idn.KIK.country=DE, Synch.Sync.mode=0, Synch.ProcPrep.BPD=0, Synch.MsgHead.dialogid=0, Synch.MsgHead.msgnum=1, Synch.Idn.sysStatus=1, Synch.ProcPrep.UPD=0, Synch.ProcPrep.prodName=HBCI4Java, Synch.Idn.sysid=0, Synch.MsgTail.msgnum=1, Synch.ProcPrep.prodVersion=2.5, Synch.Idn.KIK.blz=12345678}
<DBG> [2015.10.06 19:44:57.068] [main/main] manager.HBCIKernelImpl: trying to insert signature
<DBG> [2015.10.06 19:44:57.069] [main/main] security.Sig: setting secfunc to 999
<DBG> [2015.10.06 19:44:57.069] [main/main] security.Sig: setting cid to
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting role to 1
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting range to 1
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyblz to 12345678
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keycountry to DE
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyuserid to XXXXXXX
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keynum to 0
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting keyversion to 0
<DBG> [2015.10.06 19:44:57.070] [main/main] security.Sig: setting sysid to 0
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigid to 1
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigalg to 10
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting sigmode to 16
<DBG> [2015.10.06 19:44:57.071] [main/main] security.Sig: setting hashalg to 999
<DBG> [2015.10.06 19:44:57.075] [main/main] passport.HBCIPassportPinTan: writing to passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat_7790155181956074736
<DBG> [2015.10.06 19:44:57.075] [main/main] passport.HBCIPassportPinTan: saving two step mechs: []
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: saving current tan method: 999
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: closing output stream
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: deleting old passport file /Users/Martijn/Documents/hbci4java/my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.076] [main/main] passport.HBCIPassportPinTan: renaming my_passport_pintan.dat_7790155181956074736 to my_passport_pintan.dat
<DBG> [2015.10.06 19:44:57.079] [main/main] passport.HBCIPassportPinTan: onestep method - checking GVs to decide whether or not we need a TAN
<WRN> [2015.10.06 19:44:57.079] [main/main] passport.HBCIPassportPinTan: the job with the code HNSHK seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKIDN seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKVVB seems not to be allowed with PIN/TAN
<WRN> [2015.10.06 19:44:57.080] [main/main] passport.HBCIPassportPinTan: the job with the code HKSYN seems not to be allowed with PIN/TAN
<DB2> [2015.10.06 19:44:57.082] [main/main] manager.HBCIKernelImpl: sending message: HNHBK:1:3+000000000245+220+0+1'HNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX'HNHBS:7:1+1'
<DBG> [2015.10.06 19:44:57.082] [main/main] manager.HBCIKernelImpl: trying to encrypt message
<DBG> [2015.10.06 19:44:57.083] [main/main] security.Crypt: setting secfunc to 998
<DBG> [2015.10.06 19:44:57.083] [main/main] security.Crypt: setting keytype to 5
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting blz to XXXXX678
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting country to DE
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keyuserid to XXXXXXX
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keynum to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting keyversion to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting cid to
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting sysId to 0
<DBG> [2015.10.06 19:44:57.084] [main/main] security.Crypt: setting role to 1
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting alg to 13
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting mode to 2
<DBG> [2015.10.06 19:44:57.085] [main/main] security.Crypt: setting compfunc to 0
<DBG> [2015.10.06 19:44:57.087] [main/main] protocol.MSG: user data: {Synch.ProcPrep.lang=0, Crypted.CryptHead.KeyName.KIK.blz=XXXXX678, Crypted.CryptData.data=BHNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX', Crypted.CryptHead.SecProfile.method=PIN, Synch.Sync.mode=0, Crypted.CryptHead.secfunc=998, Synch.MsgTail.msgnum=1, Crypted.MsgTail.msgnum=1, Synch.Idn.KIK.blz=XXXXX678, Crypted.CryptHead.CryptAlg.keytype=5, Crypted.CryptHead.role=1, Synch.Idn.sysStatus=1, Synch.ProcPrep.UPD=0, Synch.ProcPrep.prodName=HBCI4Java, Crypted.CryptHead.CryptAlg.enckey=B\00\00\00\00\00\00\00\00, Crypted.CryptHead.KeyName.userid=XXXXXXX, Crypted.CryptHead.SecIdnDetails.sysid=0, Crypted.CryptHead.SecIdnDetails.func=1, Crypted.CryptHead.CryptAlg.alg=13, Synch.ProcPrep.BPD=0, Crypted.CryptHead.CryptAlg.mode=2, Crypted.CryptHead.KeyName.KIK.country=DE, Synch.MsgHead.dialogid=0, Synch.Idn.customerid=XXXXXXX, Crypted.CryptHead.KeyName.keyversion=0, Crypted.CryptHead.SecProfile.version=1, Crypted.CryptHead.SecTimestamp.time=19:44:57, Synch.MsgHead.msgnum=1, Crypted.CryptHead.SecTimestamp.date=2015-10-06, Crypted.CryptHead.KeyName.keynum=0, Synch.ProcPrep.prodVersion=2.5, Crypted.MsgHead.msgnum=1, Crypted.CryptHead.compfunc=0, Synch.Idn.sysid=0, Crypted.MsgHead.dialogid=0, Synch.Idn.KIK.country=DE}
<WRN> [2015.10.06 19:44:57.088] [main/main] protocol.MSG: could not insert the following user-defined data into message: Crypted.CryptHead.SecProfile.method=PIN
<WRN> [2015.10.06 19:44:57.089] [main/main] protocol.MSG: could not insert the following user-defined data into message: Crypted.CryptHead.SecProfile.version=1
<DB2> [2015.10.06 19:44:57.091] [main/main] manager.HBCIKernelImpl: encrypted message to be sent: HNHBK:1:3+000000000356+220+0+1'HNVSK:998:2+998+1+1::0+1:20151006:194457+2:2:13:@8@\00\00\00\00\00\00\00\00:5:1+280:XXXXX678:XXXXXXX:V:0:0+0'HNVSD:999:1+@202@HNSHK:2:3+999+1055507019+1+1+1::0+1+1:20151006:194457+1:999:1+6:10:16+280:XXXXX678:XXXXXXX:S:0:0'HKIDN:3:2+280:XXXXX678+XXXXXXX+0+1'HKVVB:4:2+0+0+0+HBCI4Java+2.5'HKSYN:5:2+0'HNSHA:6:1+1055507019++XXXXX''HNHBS:7:1+1'
<DBG> [2015.10.06 19:44:57.091] [main/main] manager.HBCIKernelImpl: communicating dialogid/msgnum 0/1
<DBG> [2015.10.06 19:44:57.091] [main/main] comm.FilterBase64: using filter: MIM (base64)
<INF> [2015.10.06 19:44:57.091] [main/main] comm.CommPinTan: creating a connection to https://127.0.0.1:8443/pintan/PinTanServlet and checking the certificate
<WRN> [2015.10.06 19:44:57.091] [main/main] comm.PinTanSSLSocketFactory: creating socket factory with disabled cert checking
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: connecting to server
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: activating modified socket factory for checkCert=false and debugging=false
<DBG> [2015.10.06 19:44:57.092] [main/main] comm.CommPinTan: activating modified hostname verifier because cert checking is disabled
<DB2> [2015.10.06 19:44:57.092] [main/main] comm.PinTanSSLSocketFactory: createSocket()
<DBG> [2015.10.06 19:44:57.102] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> server cert always OK
<DBG> [2015.10.06 19:44:57.103] [main/main] comm.PinTanSSLTrustManager: cert checking disabled -> will return 'accepted issuers'
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.PinTanSSLHostnameVerifier: cert checking disabled -> hostname always OK
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: writing data to output stream
<DBG> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: closing output stream
<INF> [2015.10.06 19:44:57.109] [main/main] comm.CommPinTan: waiting for response
<DBG> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: found messagesize: 240
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: received 1 bytes
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: we still need 239 bytes
<DB2> [2015.10.06 19:44:57.182] [main/main] comm.CommPinTan: received 239 bytes
<DB2> [2015.10.06 19:44:57.183] [main/main] comm.CommPinTan: we still need 0 bytes
<DBG> [2015.10.06 19:44:57.183] [main/main] comm.CommPinTan: closing communication line
<DB2> [2015.10.06 19:44:57.183] [main/main] comm.Comm: received message: HNHBK:1:3+000000000179+220+321825383074345556+1+321825383074345556:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.183] [main/main] comm.Comm: trying to parse message as crypted message
<DBG> [2015.10.06 19:44:57.184] [main/main] comm.Comm: message seems not to be encrypted; tring to parse it as SynchRes message
<DB2> [2015.10.06 19:44:57.194] [main/main] manager.HBCIKernelImpl: received message after decryption: HNHBK:1:3+000000000179+220+321825383074345556+1+321825383074345556:1'HIRMG:2:2+9800::org.kapott.hbci.exceptions.InitializingException?: error while initializing eleme'HNHBS:3:1+1'
<DBG> [2015.10.06 19:44:57.194] [main/main] manager.HBCIKernelImpl: extracting data from received message
<ERR> [2015.10.06 19:44:57.195] [main/main] status.HBCIStatus: HBCI error code: 9800:org.kapott.hbci.exceptions.InitializingException: error while initializing eleme
<DBG> [2015.10.06 19:44:57.195] [main/main] manager.HBCIKernelImpl: looking for a signature
<WRN> [2015.10.06 19:44:57.195] [main/main] security.Sig: message has no signature
<DBG> [2015.10.06 19:44:57.195] [main/main] manager.HBCIKernelImpl: looking if message is encrypted
<ERR> [2015.10.06 19:44:57.195] [main/main] manager.HBCIUtils: org.kapott.hbci.exceptions.HBCI_Exception: message is expected to be encrypted
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:454)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:178)
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:442)
at org.kapott.hbci.manager.HBCIUser.updateUserData(HBCIUser.java:665)
at org.kapott.hbci.manager.HBCIUser.register(HBCIUser.java:690)
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:279)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:133)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
<DBG> [2015.10.06 19:44:57.195] [main/main] passport.AbstractPinTanPassport: dialog init ended with errors - searching for return code 'wrong PIN'
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: search for 3920s in response to detect allowed twostep secmechs
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: (re)checking selected pintan secmech
<DBG> [2015.10.06 19:44:57.196] [main/main] passport.AbstractPinTanPassport: autosecfunc: there is only one pintan method (999) supported - choosing this automatically
Exception in thread "main" org.kapott.hbci.exceptions.HBCI_Exception: can not create HBCI handle
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:142)
at org.kapott.hbci.tools.HBCIBatch.main(HBCIBatch.java:255)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: can not register user
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:281)
at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:133)
... 1 more
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: error while fetching new sysid from institute
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:474)
at org.kapott.hbci.manager.HBCIUser.updateUserData(HBCIUser.java:665)
at org.kapott.hbci.manager.HBCIUser.register(HBCIUser.java:690)
at org.kapott.hbci.manager.HBCIHandler.registerUser(HBCIHandler.java:279)
... 2 more
Caused by: org.kapott.hbci.exceptions.ProcessException: error while fetching new sysid from institute
at org.kapott.hbci.manager.HBCIUser.fetchSysId(HBCIUser.java:461)
... 5 more
Apparently, the needtan property is incorrect and it should be 'J' or 'N' but I'm not sure where I can specify this user-defined property. Or is there something else that is causing the error?
Any help would be greatly appreciated. Thanks in advance
