[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] closing dialog
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$5.run] dialog closed
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] notifying listeners
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] have to determine my rdh-profile-version, but have no information about it yet
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] no user keys found in passport - so we use the highest available profile
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] using RDH profile  taken from supported profiles (BPD)
[Mon Oct 28 11:06:22 CET 2019][WARN][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] unable to determine rdh-profile-version using BPD, using default version 10
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving passport data to /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.gui.DialogFactory.getCacheKey] using cache key: hibiscus.pin./home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:22 CET 2019][TRACE][main][de.willuhn.jameica.services.BeanService.get] searching for bean HBCISynchronizeBackend
[Mon Oct 28 11:06:22 CET 2019][TRACE][main][de.willuhn.jameica.services.BeanService.get]   found in context scope
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$1.run] modal type: primary
[Mon Oct 28 11:06:22 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$4.run] using custom dialog size: 550x-1
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] closing dialog
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$5.run] dialog closed
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] notifying listeners
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.gui.DialogFactory.getCacheKey] using cache key: hibiscus.pin./home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.security.crypto.RSAEngine.encrypt] creating cipher
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.security.crypto.RSAEngine.encrypt] encrypting data
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.security.crypto.RSAEngine.encrypt] using block size (in bytes): 255
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.callback] callback [reason 21] handled by class de.willuhn.jameica.hbci.passports.rdh.server.PassportHandleImpl
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] passport data saved using LegacyFormat
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving file /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] renaming hibiscus-1572256981534.rdh_2828672949894394995 to hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new file now exists: /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] loading passport data from /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] passport data loaded using LegacyFormat
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving passport data to /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] passport data saved using LegacyFormat
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving file /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] deleting old file /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] renaming hibiscus-1572256981534.rdh_8816675277437182171 to hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new file now exists: /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@6a1d3225
[Mon Oct 28 11:06:26 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$1.run] modal type: primary
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] closing dialog
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$5.run] dialog closed
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] notifying listeners
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey] using hbci version: 300
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] registering institute
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] hole kreditinstituts-spezifische Daten
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Aktualisiere Bankparameter (BPD)
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating dialog DialogInitAnon, id 0, message number: 1, execution count: 0
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating new raw message DialogInitAnon
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.MsgHead.dialogid to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.MsgHead.msgnum to "1"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.MsgTail.msgnum to "1"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.Idn.KIK.blz to "51390000"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.Idn.KIK.country to "DE"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.ProcPrep.BPD to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.ProcPrep.UPD to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.ProcPrep.lang to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.ProcPrep.prodName to "A44C2953982351617D475443E"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.ProcPrep.prodVersion to "2.8"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.Idn.customerid to "9999999999"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.Idn.sysid to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.Idn.sysStatus to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.SecProfile.method to "RDH"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.SecProfile.version to "10"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.KeyName.keytype to "V"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.KeyName.KIK.country to "DE"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.KeyName.KIK.blz to "51390000"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogInitAnon.KeyReq.KeyName.userid to "null"
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: kann HBCI-Wert für KeyReq.KeyName.userid nicht auf null setzen
  at org.kapott.hbci.manager.HBCIKernelImpl.rawSet(HBCIKernelImpl.java:164)
  at org.kapott.hbci.passport.AbstractRDHPassport.onDialogEvent(AbstractRDHPassport.java:406)
  at org.kapott.hbci.dialog.AbstractRawHBCIDialog.execute(AbstractRawHBCIDialog.java:75)
  at org.kapott.hbci.manager.HBCIInstitute.fetchBPD(HBCIInstitute.java:276)
  at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:372)
  at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:248)
  at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
  at de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey(HBCI4JavaFormat.java:130)
  at de.willuhn.jameica.hbci.passports.rdh.RDHKeyFactory.createKey(RDHKeyFactory.java:189)
  at de.willuhn.jameica.hbci.passports.rdh.Controller.startCreate(Controller.java:713)
  at de.willuhn.jameica.hbci.passports.rdh.View$2.handleAction(View.java:56)
  at de.willuhn.jameica.gui.parts.Button$1$1.run(Button.java:174)
  at org.eclipse.swt.custom.BusyIndicator.showWhile(Unknown Source)
  at de.willuhn.jameica.gui.GUI$6.run(GUI.java:908)
  at org.eclipse.swt.widgets.Synchronizer.syncExec(Unknown Source)
  at org.eclipse.swt.widgets.Display.syncExec(Unknown Source)
  at de.willuhn.jameica.gui.GUI.startSync(GUI.java:904)
  at de.willuhn.jameica.gui.parts.Button$1.widgetSelected(Button.java:168)
  at org.eclipse.swt.widgets.TypedListener.handleEvent(Unknown Source)
  at org.eclipse.swt.widgets.EventTable.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Widget.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.runDeferredEvents(Unknown Source)
  at org.eclipse.swt.widgets.Display.readAndDispatch(Unknown Source)
  at de.willuhn.jameica.gui.GUI.loop(GUI.java:925)
  at de.willuhn.jameica.gui.GUI.init(GUI.java:328)
  at de.willuhn.jameica.system.Application.init(Application.java:145)
  at de.willuhn.jameica.system.Application.newInstance(Application.java:87)
  at de.willuhn.jameica.Main.main(Main.java:75)
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] FAILED! - maybe this institute does not support anonymous logins
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we will nevertheless go on
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if requested hbci parameters are supported
[Mon Oct 28 11:06:43 CET 2019][WARN][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] can not check if requested parameters are supported
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] hole öffentliche Schlüssel der Bank
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Rufe Institutsschlüssel ab
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating dialog FirstKeyReq, id 0, message number: 1, execution count: 0
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating new raw message FirstKeyReq
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.MsgHead.dialogid to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.MsgHead.msgnum to "1"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.MsgTail.msgnum to "1"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.Idn.KIK.blz to "51390000"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.Idn.KIK.country to "DE"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.ProcPrep.BPD to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.ProcPrep.UPD to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.ProcPrep.lang to "0"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.ProcPrep.prodName to "A44C2953982351617D475443E"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.ProcPrep.prodVersion to "2.8"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq.SecProfile.method to "RDH"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq.SecProfile.version to "10"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq.KeyName.keytype to "V"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq.KeyName.KIK.blz to "51390000"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq.KeyName.KIK.country to "DE"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq_2.SecProfile.method to "RDH"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq_2.SecProfile.version to "10"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq_2.KeyName.keytype to "S"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq_2.KeyName.KIK.blz to "51390000"
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property FirstKeyReq.KeyReq_2.KeyName.KIK.country to "DE"
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sending message using dialog FirstKeyReq, id 0, message number: 1
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] generating raw message FirstKeyReq
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht FirstKeyReq
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sending message: HNHBK:1:3+000000000227+300+0+1'HKIDN:2:2+280:51390000+9999999999+0+0'HKVVB:3:3+0+0+0+A44C2953982351617D475443E+2.8'HKISA:4:3+2+124+RDH:10+280:51390000:999:V:999:999'HKISA:5:3+2+124+RDH:10+280:51390000:999:S:999:999'HNHBS:6:1+1'
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] communicating dialogid/msgnum 0/1
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] using filter: none
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] opening connection to hbci01.fiducia.de:3000
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Versende HBCI-Nachricht
[Mon Oct 28 11:06:43 CET 2019][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@175a4743
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Warte auf Antwortdaten
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Warte auf Antwortdaten
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found message size: 12568
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 11544 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 10520 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 9496 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 8472 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 225 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 8247 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 7223 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 6199 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 5175 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 4151 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 3127 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 2103 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 639 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 1464 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 1024 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 440 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received 440 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] we still need 0 bytes
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received message: HNHBK:...
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] Lese empfangene Antwortnachricht (CryptedRes)
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to parse message as crypted message
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] message seems not to be encrypted; tring to parse it as FirstKeyReqRes message
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] Lese empfangene Antwortnachricht (FirstKeyReqRes)
[Mon Oct 28 11:06:43 CET 2019][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@4a676dec
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] received message after decryption: HNHBK:1:3...
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting data from received message
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking for a signature
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Überprüfe Signatur der Antwortnachricht
[Mon Oct 28 11:06:43 CET 2019][WARN][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] can not check signature - no signature key available
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking if message is encrypted
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new dialog-id: AL9102811064318
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting BPD from results
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] installed new BPD with version 10
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] kreditinstituts-spezifische Daten erhalten - neue Version ist 10
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep78: select PROPERTY.* from PROPERTY where name = ? {1: 'bpd.....cacheupdate'}
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep79: select VERSION.* from VERSION where name = ? {1: 'bpd....'}
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] BPD cache update state [expired: false, new version: false]
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting public institute keys from results
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found key DE_51390000_0_V_10_1
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found key DE_51390000_0_S_10_1
[Mon Oct 28 11:06:43 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] öffentliche Schlüssel der Bank erhalten
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$1.run] modal type: primary
[Mon Oct 28 11:06:43 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] using hash instance SHA-256/null
[Mon Oct 28 11:06:43 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$4.run] using custom dialog size: 540x-1
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] closing dialog
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog$5.run] dialog closed
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.gui.dialogs.AbstractDialog.close] notifying listeners
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving passport data to /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] passport data saved using LegacyFormat
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving file /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] deleting old file /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] renaming hibiscus-1572256981534.rdh_5716657418676490370 to hibiscus-1572256981534.rdh
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new file now exists: /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating dialog DialogEnd, id AL9102811064318, message number: 2, execution count: 0
[Mon Oct 28 11:07:36 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] creating new raw message DialogEnd
[Mon Oct 28 11:07:36 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogEnd.MsgHead.dialogid to "AL9102811064318"
[Mon Oct 28 11:07:36 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogEnd.MsgHead.msgnum to "2"
[Mon Oct 28 11:07:36 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogEnd.MsgTail.msgnum to "2"
[Mon Oct 28 11:07:36 CET 2019][TRACE][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting raw property DialogEnd.DialogEndS.dialogid to "AL9102811064318"
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sending message using dialog DialogEnd, id AL9102811064318, message number: 2
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] generating raw message DialogEnd
[Mon Oct 28 11:07:36 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht DialogEnd
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to insert signature
[Mon Oct 28 11:07:36 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Mon Oct 28 11:07:36 CET 2019][WARN][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] can not sign - no signature key available
[Mon Oct 28 11:07:36 CET 2019][ERROR][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Erstellen der digitalen Signatur
  at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:256)
  at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:176)
  at org.kapott.hbci.dialog.AbstractRawHBCIDialog.sendData(AbstractRawHBCIDialog.java:109)
  at org.kapott.hbci.dialog.HBCIDialogEnd.sendData(HBCIDialogEnd.java:84)
  at org.kapott.hbci.dialog.AbstractRawHBCIDialog.execute(AbstractRawHBCIDialog.java:82)
  at org.kapott.hbci.manager.HBCIInstitute.fetchKeys(HBCIInstitute.java:357)
  at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:373)
  at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:248)
  at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
  at de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey(HBCI4JavaFormat.java:130)
  at de.willuhn.jameica.hbci.passports.rdh.RDHKeyFactory.createKey(RDHKeyFactory.java:189)
  at de.willuhn.jameica.hbci.passports.rdh.Controller.startCreate(Controller.java:713)
  at de.willuhn.jameica.hbci.passports.rdh.View$2.handleAction(View.java:56)
  at de.willuhn.jameica.gui.parts.Button$1$1.run(Button.java:174)
  at org.eclipse.swt.custom.BusyIndicator.showWhile(Unknown Source)
  at de.willuhn.jameica.gui.GUI$6.run(GUI.java:908)
  at org.eclipse.swt.widgets.Synchronizer.syncExec(Unknown Source)
  at org.eclipse.swt.widgets.Display.syncExec(Unknown Source)
  at de.willuhn.jameica.gui.GUI.startSync(GUI.java:904)
  at de.willuhn.jameica.gui.parts.Button$1.widgetSelected(Button.java:168)
  at org.eclipse.swt.widgets.TypedListener.handleEvent(Unknown Source)
  at org.eclipse.swt.widgets.EventTable.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Widget.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.runDeferredEvents(Unknown Source)
  at org.eclipse.swt.widgets.Display.readAndDispatch(Unknown Source)
  at de.willuhn.jameica.gui.GUI.loop(GUI.java:925)
  at de.willuhn.jameica.gui.GUI.init(GUI.java:328)
  at de.willuhn.jameica.system.Application.init(Application.java:145)
  at de.willuhn.jameica.system.Application.newInstance(Application.java:87)
  at de.willuhn.jameica.Main.main(Main.java:75) org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1608)
[Mon Oct 28 11:07:36 CET 2019][INFO][main][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Dialog beendet
[Mon Oct 28 11:07:36 CET 2019][ERROR][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] dialog end failed: org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Erstellen der digitalen Signatur org.kapott.hbci.dialog.HBCIDialogEnd.checkResult(HBCIDialogEnd.java:111)
[Mon Oct 28 11:07:36 CET 2019][ERROR][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.ProcessException: Dialog-Ende fehlgeschlagen
  at org.kapott.hbci.dialog.HBCIDialogEnd.checkResult(HBCIDialogEnd.java:113)
  at org.kapott.hbci.dialog.AbstractRawHBCIDialog.execute(AbstractRawHBCIDialog.java:92)
  at org.kapott.hbci.manager.HBCIInstitute.fetchKeys(HBCIInstitute.java:357)
  at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:373)
  at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:248)
  at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
  at de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey(HBCI4JavaFormat.java:130)
  at de.willuhn.jameica.hbci.passports.rdh.RDHKeyFactory.createKey(RDHKeyFactory.java:189)
  at de.willuhn.jameica.hbci.passports.rdh.Controller.startCreate(Controller.java:713)
  at de.willuhn.jameica.hbci.passports.rdh.View$2.handleAction(View.java:56)
  at de.willuhn.jameica.gui.parts.Button$1$1.run(Button.java:174)
  at org.eclipse.swt.custom.BusyIndicator.showWhile(Unknown Source)
  at de.willuhn.jameica.gui.GUI$6.run(GUI.java:908)
  at org.eclipse.swt.widgets.Synchronizer.syncExec(Unknown Source)
  at org.eclipse.swt.widgets.Display.syncExec(Unknown Source)
  at de.willuhn.jameica.gui.GUI.startSync(GUI.java:904)
  at de.willuhn.jameica.gui.parts.Button$1.widgetSelected(Button.java:168)
  at org.eclipse.swt.widgets.TypedListener.handleEvent(Unknown Source)
  at org.eclipse.swt.widgets.EventTable.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Widget.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.runDeferredEvents(Unknown Source)
  at org.eclipse.swt.widgets.Display.readAndDispatch(Unknown Source)
  at de.willuhn.jameica.gui.GUI.loop(GUI.java:925)
  at de.willuhn.jameica.gui.GUI.init(GUI.java:328)
  at de.willuhn.jameica.system.Application.init(Application.java:145)
  at de.willuhn.jameica.system.Application.newInstance(Application.java:87)
  at de.willuhn.jameica.Main.main(Main.java:75) org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1608)
[Mon Oct 28 11:07:36 CET 2019][DEBUG][main][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing communication line
[Mon Oct 28 11:07:36 CET 2019][ERROR][main][de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey] unable to create key /home/.../.jameica/hibiscus/hibiscus-1572256981534.rdh
org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Erzeugen eines HBCIHandler Objektes
  at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:145)
  at de.willuhn.jameica.hbci.passports.rdh.keyformat.HBCI4JavaFormat.createKey(HBCI4JavaFormat.java:130)
  at de.willuhn.jameica.hbci.passports.rdh.RDHKeyFactory.createKey(RDHKeyFactory.java:189)
  at de.willuhn.jameica.hbci.passports.rdh.Controller.startCreate(Controller.java:713)
  at de.willuhn.jameica.hbci.passports.rdh.View$2.handleAction(View.java:56)
  at de.willuhn.jameica.gui.parts.Button$1$1.run(Button.java:174)
  at org.eclipse.swt.custom.BusyIndicator.showWhile(Unknown Source)
  at de.willuhn.jameica.gui.GUI$6.run(GUI.java:908)
  at org.eclipse.swt.widgets.Synchronizer.syncExec(Unknown Source)
  at org.eclipse.swt.widgets.Display.syncExec(Unknown Source)
  at de.willuhn.jameica.gui.GUI.startSync(GUI.java:904)
  at de.willuhn.jameica.gui.parts.Button$1.widgetSelected(Button.java:168)
  at org.eclipse.swt.widgets.TypedListener.handleEvent(Unknown Source)
  at org.eclipse.swt.widgets.EventTable.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Widget.sendEvent(Unknown Source)
  at org.eclipse.swt.widgets.Display.runDeferredEvents(Unknown Source)
  at org.eclipse.swt.widgets.Display.readAndDispatch(Unknown Source)
  at de.willuhn.jameica.gui.GUI.loop(GUI.java:925)
  at de.willuhn.jameica.gui.GUI.init(GUI.java:328)
  at de.willuhn.jameica.system.Application.init(Application.java:145)
  at de.willuhn.jameica.system.Application.newInstance(Application.java:87)
  at de.willuhn.jameica.Main.main(Main.java:75)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Registrieren der Institutsdaten 
  at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:250)
  at org.kapott.hbci.manager.HBCIHandler.<init>(HBCIHandler.java:132)
  ... 22 more
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Abholen der öffentlichen Institutsschlüssel
  at org.kapott.hbci.manager.HBCIInstitute.fetchKeys(HBCIInstitute.java:364)
  at org.kapott.hbci.manager.HBCIInstitute.register(HBCIInstitute.java:373)
  at org.kapott.hbci.manager.HBCIHandler.registerInstitute(HBCIHandler.java:248)
  ... 23 more
Caused by: org.kapott.hbci.exceptions.ProcessException: Dialog-Ende fehlgeschlagen
  at org.kapott.hbci.dialog.HBCIDialogEnd.checkResult(HBCIDialogEnd.java:113)
  at org.kapott.hbci.dialog.AbstractRawHBCIDialog.execute(AbstractRawHBCIDialog.java:92)
  at org.kapott.hbci.manager.HBCIInstitute.fetchKeys(HBCIInstitute.java:357)
  ... 25 more

[Mon Oct 28 11:07:36 CET 2019][ERROR][main][de.willuhn.jameica.hbci.passports.rdh.RDHKeyFactory.createKey] Fehler beim Erstellen des Schlüssels: Fehler beim Erzeugen eines HBCIHandler Objektes
[Mon Oct 28 11:07:36 CET 2019][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message [Mon Oct 28 11:07:36 CET 2019][ERROR] Fehler beim Erstellen des Schlüssels: Fehler beim Erzeugen eines HBCIHandler Objektes