Bei im Moment vermutlich "vorgemerkten Umsätzen" der VR-Bank läuft der Abruf eigentlich mit einem Fehler durch, wird aber als erfolgreich gemeldet.
Es wurden Überweisungen am späten Abend getätigt die nun vermutlich beim direkten Abruf danach wie immer erstmal "grau" und ohne Betrag übernommen würden. Diese erscheinen aber (auch) nicht.
(Achja, da fällt mir ein, würde es zwar nochmals beobachten, aber diese verschwinden dann immer noch nicht wenn die richtige Buchung abgerufen wird?)
Ist da bei mir immer noch was kaputt? Aber der Rest ging nun sauber.
Hier die Log:
Es wurden Überweisungen am späten Abend getätigt die nun vermutlich beim direkten Abruf danach wie immer erstmal "grau" und ohne Betrag übernommen würden. Diese erscheinen aber (auch) nicht.
(Achja, da fällt mir ein, würde es zwar nochmals beobachten, aber diese verschwinden dann immer noch nicht wenn die richtige Buchung abgerufen wird?)
Ist da bei mir immer noch was kaputt? Aber der Rest ging nun sauber.
Hier die Log:
Code
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sysId to KdjUN39OwWQBAACAIqQKhW+owAQA
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting alg to 13
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting mode to 2
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting compfunc to 0
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] communicating dialogid/msgnum KA8121221130896/3
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] versende HBCI-Nachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@5240fb79
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] connecting to server
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] activating modified socket factory for checkCert=true and debugging=true
[Wed Dec 12 21:13:09 CET 2018][WARN][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] no log.ssl.filename specified - logging to HBCI4Java logger
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing data to output stream
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] warte auf Antwortdaten
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] warte auf Antwortdaten
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found messagesize: 7080
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing communication line
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CryptedRes)
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to parse message as crypted message
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] entschlüssele Antwortnachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] acquire crypt instance
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypting using org.kapott.hbci.security.Crypt@39b8ef90
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypted
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] free crypt
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt freed
[Wed Dec 12 21:13:09 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@102a8c93
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSegment
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongStatusSegOrder
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongSequenceNumbers
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RMissingMsgRef
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RHBCIVersion
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSigIdLeadingZero
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSuppHBCIVersion
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSecTypeTAN
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsDelimiters
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsEmptyBDateSets
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting done
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CustomMsgRes)
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting data from received message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking for a signature
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] überprüfe Signatur der Antwortnachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking if message is encrypted
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] basic values for KUmsZeitCamt1 set to KA8121221130896/3/3
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sepa version given in sepadescr: urn:iso:std:iso:20022:tech:xsd:camt.052.001.02
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sepa version according to data: urn:iso:std:iso:20022:tech:xsd:camt.052.001.02
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to init SEPA parser: org.kapott.hbci.GV.parsers.ParseCamt05200102
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] unable to parse camt data: null org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:165)
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Speichern der Ergebnisdaten für Job KUmsZeitCamt1 im JobResult-Objekt
at org.kapott.hbci.GV.HBCIJobImpl.fillJobResult(HBCIJobImpl.java:866)
at org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:319)
at org.kapott.hbci.manager.HBCIDialog.doIt(HBCIDialog.java:410)
at org.kapott.hbci.manager.HBCIHandler.execute(HBCIHandler.java:515)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs(HBCISynchronizeBackend.java:373)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.sync(HBCISynchronizeBackend.java:295)
at de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run(AbstractSynchronizeBackend.java:406)
at de.willuhn.jameica.gui.GUI$7.run(GUI.java:1060)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: Error parsing CAMT document
at org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:166)
at org.kapott.hbci.GV.HBCIJobImpl.fillJobResult(HBCIJobImpl.java:858)
... 7 more
Caused by: java.lang.NullPointerException
at org.kapott.hbci.GV.parsers.ParseCamt05200102.createDay(ParseCamt05200102.java:316)
at org.kapott.hbci.GV.parsers.ParseCamt05200102.parse(ParseCamt05200102.java:88)
at org.kapott.hbci.GV.parsers.ParseCamt05200102.parse(ParseCamt05200102.java:53)
at org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:160)
... 8 more org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1608)
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] aborting current loop because of errors org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:329)
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Ende aus
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] beende Dialog
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] generating raw message DialogEnd
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] erzeuge HBCI-Nachricht DialogEnd
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to insert signature
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting secfunc to 942
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting cid to
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting range to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyblz to 79090000
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keycountry to DE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyuserid to XXXXXXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keynum to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyversion to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sysid to KdjUN39OwWQBAACAIqQKhW+owAQA
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigid to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigalg to 10
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigmode to 16
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting hashalg to 999
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing to passport file jameica/hibiscus/passports/1332496598598.pt_5634017887251767960
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving two step mechs: [942]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving current tan method: 942
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving passport file jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] deleting old passport file jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] renaming 1332496598598.pt_5634017887251767960 to 1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new passport file now exists: jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] twostep method - checking passport(challenge) to decide whether or not we need a TAN
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] will not sign with a TAN, because there is no challenge
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to encrypt message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] verschlüssele HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting secfunc to 998
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keytype to 5
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting blz to 79090000
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting country to DE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyuserid to XXXXXXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keynum to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyversion to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting cid to
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sysId to KdjUN39OwWQBAACAIqQKhW+owAQA
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting alg to 13
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting mode to 2
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting compfunc to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] communicating dialogid/msgnum KA8121221130896/4
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] versende HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@48101b3a
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] connecting to server
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] activating modified socket factory for checkCert=true and debugging=true
[Wed Dec 12 21:13:10 CET 2018][WARN][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] no log.ssl.filename specified - logging to HBCI4Java logger
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing data to output stream
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] warte auf Antwortdaten
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] warte auf Antwortdaten
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found messagesize: 602
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing communication line
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CryptedRes)
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to parse message as crypted message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] entschlüssele Antwortnachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] acquire crypt instance
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypting using org.kapott.hbci.security.Crypt@39b8ef90
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypted
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] free crypt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@56227d4d
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt freed
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSegment
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongStatusSegOrder
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongSequenceNumbers
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RMissingMsgRef
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RHBCIVersion
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSigIdLeadingZero
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSuppHBCIVersion
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSecTypeTAN
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsDelimiters
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsEmptyBDateSets
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting done
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (DialogEndRes)
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting data from received message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking for a signature
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] überprüfe Signatur der Antwortnachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking if message is encrypted
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Dialog beendet
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing check for job SaldoReq
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] execution state: tan needed: false, executed: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] global status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] job status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] retval[0]: 0020 - *Abfrage der Kontosalden erfolgreich.
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] mark job executed [executed: true, status: OK]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep397: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.309', 2: 4, 3: 1, 4: 'Saldo abgerufen'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@36ccfc3
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep398: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.31', 2: 4, 3: 1, 4: 'Konto-Eigenschaften aktualisiert'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@47e69529
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crc-checking 79090000/XXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] CRC check for 79090000/XXXXX with alg 88: true
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getUpdateSQL] executing sql statement: prep399: update KONTO set SALDO_DATUM=? where ID=4 {1: TIMESTAMP '2018-12-12 21:13:10.309'}
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCISaldoJob.markExecuted] saldo fetched successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@f678f24
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.messaging.SaldoMessage@4a6a4390
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.server.KontoUtil.useCamt] checking if account supports CAMT
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep404: select PROPERTY.* from PROPERTY where name = ? {1: 'meta.konto.4.umsatz.camt'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.server.KontoUtil.useCamt] CAMT usage configured as: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing check for job KUmsAllCamt
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] execution state: tan needed: false, executed: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] global status: org.kapott.hbci.exceptions.HBCI_Exception: Error parsing CAMT document
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] job status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] retval[0]: 0020 - *Abfrage CAMT Umsätze erfolgreich durchgeführt
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] mark job executed [executed: true, status: OK]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep405: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.326', 2: 4, 3: 1, 4: STRINGDECODE('Ums\u00e4tze abgerufen')}
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.getMergeWindow] merge window: Mon Nov 12 00:00:00 CET 2018 - now (based on last sync)
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] got no new booked entries
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] got no new not-booked (vorgemerkte) entries
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] clean obsolete not-booked entries
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep406: select UMSATZ.* from UMSATZ where konto_id = 4 and flags = 2 ORDER BY datum DESC, id DESC
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] removed entries: 0
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] umsatz list fetched successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][main][de.willuhn.jameica.services.BeanService.get] creating new class de.willuhn.jameica.hbci.passports.pintan.server.PassportImpl
[Wed Dec 12 21:13:10 CET 2018][DEBUG][main][de.willuhn.jameica.hbci.PassportRegistry.load] [de.willuhn.jameica.hbci.passports.pintan.server.PassportImpl][PIN/TAN] instantiated successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@2f1d8fc9
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.InvokeScriptMessageConsumer.handleMessage] no script functions registered for event hibiscus.konto.saldo.changed
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@5ca118de
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@6f0cb2c1
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.close] closing resources
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.gui.action.PassportProcessCode3072.handleAction] no changed customer data in persistent data of passport found
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.passports.pintan.server.PassportHandleImpl.close] closing pin/tan passport
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.passports.pintan.server.PassportHandleImpl.close] pin/tan passport closed
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] END synchronization of account 1/1
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.updateStatus] updating synchronization status to: DONE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.messaging.NamedConcurrentQueue.sendMessage] no message consumers found, ignoring message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] stopping synchronization
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message [Wed Dec 12 21:13:10 CET 2018][OK] Synchronisierung via FinTS/HBCI erfolgreich beendet
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] finished
[Wed Dec 12 21:13:18 CET 2018][INFO][main][de.willuhn.jameica.gui.internal.parts.BackgroundTaskMonitor$2$1.handleAction] closing background task monitor snapin
[Wed Dec 12 21:13:22 CET 2018][DEBUG][main][de.willuhn.jameica.gui.parts.table.FeatureClipboard.applyShortcut] bound de.willuhn.jameica.gui.parts.table.FeatureClipboard$2@4a561f7d
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting alg to 13
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting mode to 2
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting compfunc to 0
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] communicating dialogid/msgnum KA8121221130896/3
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] versende HBCI-Nachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@5240fb79
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] connecting to server
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] activating modified socket factory for checkCert=true and debugging=true
[Wed Dec 12 21:13:09 CET 2018][WARN][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] no log.ssl.filename specified - logging to HBCI4Java logger
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing data to output stream
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] warte auf Antwortdaten
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] warte auf Antwortdaten
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found messagesize: 7080
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing communication line
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CryptedRes)
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to parse message as crypted message
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] entschlüssele Antwortnachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] acquire crypt instance
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypting using org.kapott.hbci.security.Crypt@39b8ef90
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypted
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] free crypt
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt freed
[Wed Dec 12 21:13:09 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@102a8c93
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSegment
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongStatusSegOrder
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongSequenceNumbers
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RMissingMsgRef
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RHBCIVersion
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSigIdLeadingZero
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSuppHBCIVersion
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSecTypeTAN
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsDelimiters
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsEmptyBDateSets
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting done
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CustomMsgRes)
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting data from received message
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking for a signature
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] überprüfe Signatur der Antwortnachricht
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking if message is encrypted
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] basic values for KUmsZeitCamt1 set to KA8121221130896/3/3
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sepa version given in sepadescr: urn:iso:std:iso:20022:tech:xsd:camt.052.001.02
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] sepa version according to data: urn:iso:std:iso:20022:tech:xsd:camt.052.001.02
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to init SEPA parser: org.kapott.hbci.GV.parsers.ParseCamt05200102
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] unable to parse camt data: null org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:165)
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] org.kapott.hbci.exceptions.HBCI_Exception: Fehler beim Speichern der Ergebnisdaten für Job KUmsZeitCamt1 im JobResult-Objekt
at org.kapott.hbci.GV.HBCIJobImpl.fillJobResult(HBCIJobImpl.java:866)
at org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:319)
at org.kapott.hbci.manager.HBCIDialog.doIt(HBCIDialog.java:410)
at org.kapott.hbci.manager.HBCIHandler.execute(HBCIHandler.java:515)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs(HBCISynchronizeBackend.java:373)
at de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.sync(HBCISynchronizeBackend.java:295)
at de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run(AbstractSynchronizeBackend.java:406)
at de.willuhn.jameica.gui.GUI$7.run(GUI.java:1060)
Caused by: org.kapott.hbci.exceptions.HBCI_Exception: Error parsing CAMT document
at org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:166)
at org.kapott.hbci.GV.HBCIJobImpl.fillJobResult(HBCIJobImpl.java:858)
... 7 more
Caused by: java.lang.NullPointerException
at org.kapott.hbci.GV.parsers.ParseCamt05200102.createDay(ParseCamt05200102.java:316)
at org.kapott.hbci.GV.parsers.ParseCamt05200102.parse(ParseCamt05200102.java:88)
at org.kapott.hbci.GV.parsers.ParseCamt05200102.parse(ParseCamt05200102.java:53)
at org.kapott.hbci.GV.GVKUmsAllCamt.extractResults(GVKUmsAllCamt.java:160)
... 8 more org.kapott.hbci.manager.HBCIUtils.log(HBCIUtils.java:1608)
[Wed Dec 12 21:13:09 CET 2018][ERROR][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] aborting current loop because of errors org.kapott.hbci.manager.HBCIDialog.doJobs(HBCIDialog.java:329)
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] Führe Dialog-Ende aus
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] beende Dialog
[Wed Dec 12 21:13:09 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] generating raw message DialogEnd
[Wed Dec 12 21:13:09 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] erzeuge HBCI-Nachricht DialogEnd
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to insert signature
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] signiere HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting secfunc to 942
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting cid to
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting range to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyblz to 79090000
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keycountry to DE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyuserid to XXXXXXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keynum to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyversion to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sysid to KdjUN39OwWQBAACAIqQKhW+owAQA
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigid to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigalg to 10
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sigmode to 16
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting hashalg to 999
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing to passport file jameica/hibiscus/passports/1332496598598.pt_5634017887251767960
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving two step mechs: [942]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving current tan method: 942
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] saving passport file jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] deleting old passport file jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] renaming 1332496598598.pt_5634017887251767960 to 1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] new passport file now exists: jameica/hibiscus/passports/1332496598598.pt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] twostep method - checking passport(challenge) to decide whether or not we need a TAN
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] will not sign with a TAN, because there is no challenge
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to encrypt message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] verschlüssele HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting secfunc to 998
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keytype to 5
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting blz to 79090000
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting country to DE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyuserid to XXXXXXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keynum to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting keyversion to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting cid to
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting sysId to KdjUN39OwWQBAACAIqQKhW+owAQA
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting role to 1
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting alg to 13
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting mode to 2
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] setting compfunc to 0
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] communicating dialogid/msgnum KA8121221130896/4
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] versende HBCI-Nachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@48101b3a
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] connecting to server
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] activating modified socket factory for checkCert=true and debugging=true
[Wed Dec 12 21:13:10 CET 2018][WARN][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] no log.ssl.filename specified - logging to HBCI4Java logger
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] writing data to output stream
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing output stream
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] warte auf Antwortdaten
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] warte auf Antwortdaten
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] found messagesize: 602
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] closing communication line
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (CryptedRes)
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] trying to parse message as crypted message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] entschlüssele Antwortnachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] acquire crypt instance
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] checking if crypt available in pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] yes, initializing with handlerdata + message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] adding to used pool
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt acquired
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypting using org.kapott.hbci.security.Crypt@39b8ef90
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] decrypted
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] free crypt
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@56227d4d
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crypt freed
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSegment
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongStatusSegOrder
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RWrongSequenceNumbers
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RMissingMsgRef
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RHBCIVersion
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSigIdLeadingZero
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RInvalidSuppHBCIVersion
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RSecTypeTAN
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsDelimiters
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] applying rewriter RKUmsEmptyBDateSets
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] rewriting done
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.AbstractHibiscusHBCICallback.status] parse empfangene Antwortnachricht (DialogEndRes)
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] extracting data from received message
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking for a signature
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] überprüfe Signatur der Antwortnachricht
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] looking if message is encrypted
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.status] Dialog beendet
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing check for job SaldoReq
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] execution state: tan needed: false, executed: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] global status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] job status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] retval[0]: 0020 - *Abfrage der Kontosalden erfolgreich.
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] mark job executed [executed: true, status: OK]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep397: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.309', 2: 4, 3: 1, 4: 'Saldo abgerufen'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@36ccfc3
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep398: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.31', 2: 4, 3: 1, 4: 'Konto-Eigenschaften aktualisiert'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@47e69529
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] crc-checking 79090000/XXXXX
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.HBCICallbackSWT.log] CRC check for 79090000/XXXXX with alg 88: true
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getUpdateSQL] executing sql statement: prep399: update KONTO set SALDO_DATUM=? where ID=4 {1: TIMESTAMP '2018-12-12 21:13:10.309'}
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCISaldoJob.markExecuted] saldo fetched successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@f678f24
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.messaging.SaldoMessage@4a6a4390
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.server.KontoUtil.useCamt] checking if account supports CAMT
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep404: select PROPERTY.* from PROPERTY where name = ? {1: 'meta.konto.4.umsatz.camt'}
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.hbci.server.KontoUtil.useCamt] CAMT usage configured as: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.executeJobs] executing check for job KUmsAllCamt
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] execution state: tan needed: false, executed: true
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] global status: org.kapott.hbci.exceptions.HBCI_Exception: Error parsing CAMT document
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] job status:
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.getErrorText] retval[0]: 0020 - *Abfrage CAMT Umsätze erfolgreich durchgeführt
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.AbstractHBCIJob.handleResult] mark job executed [executed: true, status: OK]
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.AbstractDBObject.getInsertSQL] executing sql statement: prep405: insert into PROTOKOLL (DATUM,KONTO_ID,TYP,KOMMENTAR) values (?,?,?,?) {1: TIMESTAMP '2018-12-12 21:13:10.326', 2: 4, 3: 1, 4: STRINGDECODE('Ums\u00e4tze abgerufen')}
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.getMergeWindow] merge window: Mon Nov 12 00:00:00 CET 2018 - now (based on last sync)
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] got no new booked entries
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] got no new not-booked (vorgemerkte) entries
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] clean obsolete not-booked entries
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.datasource.db.DBIteratorImpl.init] executing sql query: prep406: select UMSATZ.* from UMSATZ where konto_id = 4 and flags = 2 ORDER BY datum DESC, id DESC
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] removed entries: 0
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.server.hbci.HBCIUmsatzJob.markExecuted] umsatz list fetched successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][main][de.willuhn.jameica.services.BeanService.get] creating new class de.willuhn.jameica.hbci.passports.pintan.server.PassportImpl
[Wed Dec 12 21:13:10 CET 2018][DEBUG][main][de.willuhn.jameica.hbci.PassportRegistry.load] [de.willuhn.jameica.hbci.passports.pintan.server.PassportImpl][PIN/TAN] instantiated successfully
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@2f1d8fc9
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.InvokeScriptMessageConsumer.handleMessage] no script functions registered for event hibiscus.konto.saldo.changed
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.messaging.QueryMessage@5ca118de
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message de.willuhn.jameica.hbci.synchronize.hbci.HBCITraceMessage@6f0cb2c1
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.hbci.HBCISynchronizeBackend$HBCIJobGroup.close] closing resources
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.gui.action.PassportProcessCode3072.handleAction] no changed customer data in persistent data of passport found
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.passports.pintan.server.PassportHandleImpl.close] closing pin/tan passport
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.passports.pintan.server.PassportHandleImpl.close] pin/tan passport closed
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] END synchronization of account 1/1
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.updateStatus] updating synchronization status to: DONE
[Wed Dec 12 21:13:10 CET 2018][DEBUG][bg-task:Worker][de.willuhn.jameica.messaging.NamedConcurrentQueue.sendMessage] no message consumers found, ignoring message
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] stopping synchronization
[Wed Dec 12 21:13:10 CET 2018][DEBUG][pool-1-thread-1][de.willuhn.jameica.messaging.NamedConcurrentQueue.deliver] deliver message [Wed Dec 12 21:13:10 CET 2018][OK] Synchronisierung via FinTS/HBCI erfolgreich beendet
[Wed Dec 12 21:13:10 CET 2018][INFO][bg-task:Worker][de.willuhn.jameica.hbci.synchronize.AbstractSynchronizeBackend$Worker.run] finished
[Wed Dec 12 21:13:18 CET 2018][INFO][main][de.willuhn.jameica.gui.internal.parts.BackgroundTaskMonitor$2$1.handleAction] closing background task monitor snapin
[Wed Dec 12 21:13:22 CET 2018][DEBUG][main][de.willuhn.jameica.gui.parts.table.FeatureClipboard.applyShortcut] bound de.willuhn.jameica.gui.parts.table.FeatureClipboard$2@4a561f7d
