Hallo,
seit ewiger Zeit trat mit Hibiscus mal wieder ein Fehler auf. Es sollte eine Sammellastschrift ausgeführt werden:
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing jobs
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Initialisierung aus
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Führe Dialog-Initialisierung aus
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht DialogInit
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Verschlüssele HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Versende HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Warte auf Antwortdaten
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Warte auf Antwortdaten
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Entschlüssele Antwortnachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Überprüfe Signatur der Antwortnachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Benutzerparameter (UPD) aktualisiert [Bisherige Version: 540, neue Version: 541]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] nutzerspezifische Daten aktualisiert - neue Version is 541
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] UPD cache update state [expired: true, new version: true]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] updating UPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] customer 1: updated UPD- inserts: 0, updates: 2, deletions: 0
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.expireCache] expire BPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] BPD cache update state [expired: true, new version: false]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] updating BPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] customer 1: updated BPD- inserts: 0, updates: 0, deletions: 0
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Dialog initialisiert - Dialog-ID ist AT3100518044532
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Verarbeite Aufträge
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erstelle Auftragsdaten für Geschäftsvorfall SammelLastSEPA1
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht CustomMsg
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Verschlüssele HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Versende HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Warte auf Antwortdaten
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Warte auf Antwortdaten
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Entschlüssele Antwortnachricht
[Thu Oct 05 18:04:46 CEST 2023][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: *** error while decrypting
at org.kapott.hbci.security.Crypt.decryptIt(Crypt.java:339)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:364)
at org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:270)
at org.kapott.hbci.manager.HBCIDialog.doIt(HBCIDialog.java:450)
at org.kapott.hbci.manager.HBCIHandler.execute(HBCIHandler.java:544)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs(HBCISynchronizeBackend.java:352)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.sync(HBCISynchronizeBackend.java:273)
at de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run(AbstractSynchronizeBackend.java:399)
at de.willuhn.jameica.gui.GUI$7.run(GUI.java:1081)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: *** error while decrypting message
at org.kapott.hbci.passport.AbstractRDHSWPassport.decrypt(AbstractRDHSWPassport.java:404)
at org.kapott.hbci.security.Crypt.decryptIt(Crypt.java:324)
... 8 more
Caused by: java.lang.ArrayIndexOutOfBoundsException: arraycopy: source index -1 out of bounds for byte[15]
at java.base/java.lang.System.arraycopy(Native Method)
at org.kapott.hbci.passport.AbstractRDHSWPassport.decrypt(AbstractRDHSWPassport.java:388)
... 9 more org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1609)
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Ergebnisdaten für Geschäftsvorfall SammelLastSEPA1 empfangen
[Thu Oct 05 18:04:46 CEST 2023][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] aborting current loop because of errors org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:310)
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Ende aus
Beim 2. Versuch (fast) das Gleiche, die Bank meldete, daß der Auftrag bereits eingereicht wurde. ("Fehlermeldung der Bank:
9390 - *Auftrag abgelehnt, da dieser Auftrag bereits eingereicht wurde.")
Der anschließende Abruf der Umsätze funktionierte ohne Fehler.
Tja... wurde der Auftrag nun ausgeführt und was hat es mit dem Fehler überhaupt auf sich?
seit ewiger Zeit trat mit Hibiscus mal wieder ein Fehler auf. Es sollte eine Sammellastschrift ausgeführt werden:
Code
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing jobs
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Initialisierung aus
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Führe Dialog-Initialisierung aus
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht DialogInit
[Thu Oct 05 18:04:42 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Verschlüssele HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Versende HBCI-Nachricht
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Warte auf Antwortdaten
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Warte auf Antwortdaten
[Thu Oct 05 18:04:43 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Entschlüssele Antwortnachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Überprüfe Signatur der Antwortnachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Benutzerparameter (UPD) aktualisiert [Bisherige Version: 540, neue Version: 541]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] nutzerspezifische Daten aktualisiert - neue Version is 541
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] UPD cache update state [expired: true, new version: true]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] updating UPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] customer 1: updated UPD- inserts: 0, updates: 2, deletions: 0
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.expireCache] expire BPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] BPD cache update state [expired: true, new version: false]
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] updating BPD cache
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.BPDUtil.updateCache] customer 1: updated BPD- inserts: 0, updates: 0, deletions: 0
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Dialog initialisiert - Dialog-ID ist AT3100518044532
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Verarbeite Aufträge
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erstelle Auftragsdaten für Geschäftsvorfall SammelLastSEPA1
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Erzeuge HBCI-Nachricht CustomMsg
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Verschlüssele HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Versende HBCI-Nachricht
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Warte auf Antwortdaten
[Thu Oct 05 18:04:44 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Warte auf Antwortdaten
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Entschlüssele Antwortnachricht
[Thu Oct 05 18:04:46 CEST 2023][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: *** error while decrypting
at org.kapott.hbci.security.Crypt.decryptIt(Crypt.java:339)
at org.kapott.hbci.manager.HBCIKernelImpl.rawDoIt(HBCIKernelImpl.java:364)
at org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:270)
at org.kapott.hbci.manager.HBCIDialog.doIt(HBCIDialog.java:450)
at org.kapott.hbci.manager.HBCIHandler.execute(HBCIHandler.java:544)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs(HBCISynchronizeBackend.java:352)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.sync(HBCISynchronizeBackend.java:273)
at de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run(AbstractSynchronizeBackend.java:399)
at de.willuhn.jameica.gui.GUI$7.run(GUI.java:1081)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: *** error while decrypting message
at org.kapott.hbci.passport.AbstractRDHSWPassport.decrypt(AbstractRDHSWPassport.java:404)
at org.kapott.hbci.security.Crypt.decryptIt(Crypt.java:324)
... 8 more
Caused by: java.lang.ArrayIndexOutOfBoundsException: arraycopy: source index -1 out of bounds for byte[15]
at java.base/java.lang.System.arraycopy(Native Method)
at org.kapott.hbci.passport.AbstractRDHSWPassport.decrypt(AbstractRDHSWPassport.java:388)
... 9 more org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1609)
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Ergebnisdaten für Geschäftsvorfall SammelLastSEPA1 empfangen
[Thu Oct 05 18:04:46 CEST 2023][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] aborting current loop because of errors org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:310)
[Thu Oct 05 18:04:46 CEST 2023][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Ende aus
Beim 2. Versuch (fast) das Gleiche, die Bank meldete, daß der Auftrag bereits eingereicht wurde. ("Fehlermeldung der Bank:
9390 - *Auftrag abgelehnt, da dieser Auftrag bereits eingereicht wurde.")
Der anschließende Abruf der Umsätze funktionierte ohne Fehler.
Tja... wurde der Auftrag nun ausgeführt und was hat es mit dem Fehler überhaupt auf sich?