BUG: TSE Connector

ray_ray · Beitrag von **ray_ray** » Sa 3. Okt 2020, 00:36

Hallo,

ist das normal in der Version 2.06, dass das CMD-Fenster immer die Codes ununterbrochen zeigt? In der 2.05 haben wir diese Zeilen nicht gehabt.
Ich habe windows 10 mit TSE im Betrieb.

isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678102, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678103, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678105, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678106, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678107, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678110, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678111, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678112, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678116, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678116, lastSucces=1601677578
Transferred POST-Data: {"pass":"123","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
isCheckUseful = false: current = 1601678117, lastSucces=1601677578

danke für eine Rückmeldung im Vorraus!

Gruß,
Ray

Beitrag von **pichel** » Sa 3. Okt 2020, 01:06

Hallo Ray,

der TSEConnector in der 2.0.6 ist etwas "gesprächiger". Das sieht vielleicht nicht so schön aus und wahrscheinlich werde ich das in zukünftigen Versionen auch wieder zurücknehmen, aber es ist kein Fehler.

Im Gegensatz hilft es mir, wenn ich im Fehlerfall auf diese zusätzlichen Ausgaben zurückgreifen kann.

Wenn Anwender von Problemen berichten und ich mich schon mal per Teamviewer auf deren Rechner einwähle, dann ist sehr praktisch, wenn ich mit diesem Protokollausgaben das Problem schneller eingrenzen kann.

Eigentlich ist es auch sie gedacht, dass man den TSEConnector so einbindet, dass er seine Arbeit "unsichtbar" im Hintergrund verrichtet, z.B. als Service.

Gruß,

Stefan

ray_ray · Beitrag von **ray_ray** » Sa 3. Okt 2020, 18:44

Hallo Stefan,

danke für die super schnelle Antwort!

Gruß,
Ray

Beitrag von **pichel** » So 4. Okt 2020, 01:57

Hallo Ray,

ich habe in der 2.0.7 nun die Möglichkeit eingebaut, den Loglevel festzulegen und damit die Menge der Ausgaben zu begrenzen (in der Voreinstellung werden nun nur noch die wichtigsten Ausgabe inkl. Signierungsaktivitäten mitgeloggt). Es kann sein, dass das umfangreiche Logging in 2.0.6 zu Problemen mit dem verfügbaren Puffer in Windows-CMD-Shells führen konnte.

Gruß,

Stefan

misery · Beitrag von **misery** » Di 6. Okt 2020, 19:57

Moin Stefan,

ich hatte produktiv beim Café die 2.0.5 noch laufen und per systemd-Timer automatisch den TSEConnector um 8 Uhr morgens neustarten lassen. Heute ist eine TSE-Signatur zum ersten Mal fehlgeschlagen.

Code: Alles auswählen

Oct 06 08:00:04 systemd[1]: Stopping TSE...
Oct 06 08:00:04 systemd[1]: tse.service: Succeeded.
Oct 06 08:00:04 systemd[1]: Stopped TSE.
Oct 06 08:00:04 systemd[1]: Started TSE.
Oct 06 08:00:04 tse.sh[295487]: TSEConnectors kann folgende Parameter verarbeiten:
Oct 06 08:00:04 tse.sh[295487]:    pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
Oct 06 08:00:04 tse.sh[295487]:    port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
Oct 06 08:00:04 tse.sh[295487]:    mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
Oct 06 08:00:04 tse.sh[295487]:    mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
Oct 06 08:00:04 tse.sh[295487]: Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
Oct 06 08:00:04 tse.sh[295487]:    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
Oct 06 08:00:05 tse.sh[295487]: Start TSEConnector mit diesen Parametern:
Oct 06 08:00:05 tse.sh[295487]:   pass=      XXX
Oct 06 08:00:05 tse.sh[295487]:   port=      6666
Oct 06 08:00:05 tse.sh[295487]:   supplier=  swissbit
Oct 06 08:00:05 tse.sh[295487]:   mountpoint=/mnt/tse
Oct 06 08:00:05 tse.sh[295487]:   mode=      normal
Oct 06 08:00:05 tse.sh[295487]: Sync Time = 1800 seconds (30 minutes)
Oct 06 08:00:05 tse.sh[295487]: Abgeschlossen Startup des TSEConnectors
Oct 06 10:34:45 tse.sh[295487]: Transferred POST-Data: {"pass":"XXX","pin":[XXX],"puk":[XXX,6],"clientid":"ORD1","cmd":"selftest"}
Oct 06 10:34:45 tse.sh[295487]: Request Handler. Post Data: {"pass":"XXX","pin":[XXX],"puk":[XXX,6],"clientid":"ORD1","cmd":"selftest"}
Oct 06 10:34:45 tse.sh[295487]: Parse into class this string: {"pass":"XXX","pin":[XXX],"puk":[XXX,6],"clientid":"ORD1","cmd":"selftest"}
Oct 06 10:34:45 tse.sh[295487]: Start Self Test
Oct 06 10:34:59 tse.sh[295487]: Completed Self Test
Oct 06 10:34:59 tse.sh[295487]: Self Test completed
Oct 06 11:16:28 tse.sh[295487]: Transferred POST-Data: {"pass":"XXX","pin":[XXX],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90"}
Oct 06 11:16:28 tse.sh[295487]: Request Handler. Post Data: {"pass":"XXX","pin":[XXX],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90"}
Oct 06 11:16:28 tse.sh[295487]: Parse into class this string: {"pass":"XXX","pin":[XXX],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90"}
Oct 06 11:16:28 tse.sh[295487]: Start Update Time with unixTime 1601975788
Oct 06 11:16:28 tse.sh[295487]: Logged in
Oct 06 11:16:28 tse.sh[295487]: Completed Update Time at TSE
Oct 06 11:16:28 tse.sh[295487]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
Oct 06 11:16:28 tse.sh[295487]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_tse_registerClient(Native Method)
Oct 06 11:16:28 tse.sh[295487]:         at com.secureflashcard.wormapi.WormStore.tse_registerClient(WormStore.java:547)
Oct 06 11:16:28 tse.sh[295487]:         at tseconnector.commands.BaseTseCommand.registerClient(BaseTseCommand.java:58)
Oct 06 11:16:28 tse.sh[295487]:         at tseconnector.commands.BaseTseCommand.makeTseReady(BaseTseCommand.java:47)
Oct 06 11:16:28 tse.sh[295487]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:41)
Oct 06 11:16:28 tse.sh[295487]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
Oct 06 11:16:28 tse.sh[295487]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:51)
Oct 06 11:16:28 tse.sh[295487]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 06 11:16:28 tse.sh[295487]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 06 11:16:28 tse.sh[295487]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 06 11:16:28 tse.sh[295487]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 06 11:16:28 tse.sh[295487]:         at java.lang.Thread.run(Thread.java:748)
Oct 06 11:16:28 tse.sh[295487]: {"msg":"TSE not ready","status":"ERROR"}

ich habe dann gleich heute von 2.0.5 auf 2.0.7 aktualisiert. Natürlich auch den TSEConnector. Und gleich der erste Signatur-Versuch lief schief. Ich hatte dann nochmal durchgestartet. Danach ging es.

Code: Alles auswählen

Oct 06 17:46:15 systemd[1]: Stopped TSE.
Oct 06 17:53:00 systemd[1]: Started TSE.
Oct 06 17:53:00 tse.sh[298675]: TSEConnectors kann folgende Parameter verarbeiten:
Oct 06 17:53:00 tse.sh[298675]:    pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
Oct 06 17:53:00 tse.sh[298675]:    port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
Oct 06 17:53:00 tse.sh[298675]:    mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
Oct 06 17:53:00 tse.sh[298675]:    mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
Oct 06 17:53:00 tse.sh[298675]:    loglevel=number  0: nur relevante Infos, 1: Signierungen, 2: umfangreiches Logging.
Oct 06 17:53:00 tse.sh[298675]: Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
Oct 06 17:53:00 tse.sh[298675]:    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
Oct 06 17:53:01 tse.sh[298675]: Start TSEConnector mit diesen Parametern:
Oct 06 17:53:01 tse.sh[298675]:   pass=      XXX
Oct 06 17:53:01 tse.sh[298675]:   port=      6666
Oct 06 17:53:01 tse.sh[298675]:   supplier=  swissbit
Oct 06 17:53:01 tse.sh[298675]:   mountpoint=/mnt/tse
Oct 06 17:53:01 tse.sh[298675]:   mode=      normal
Oct 06 17:53:01 tse.sh[298675]:   loglevel=  1
Oct 06 17:53:01 tse.sh[298675]: TSE seems to be mounted
Oct 06 17:53:01 tse.sh[298675]: Sync Time = 1800 seconds (30 minutes)
Oct 06 17:53:01 tse.sh[298675]: Abgeschlossen Startup des TSEConnectors
Oct 06 18:35:20 tse.sh[298675]: Starte Signierung
Oct 06 18:35:20 tse.sh[298675]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 06 18:35:20 tse.sh[298675]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 06 18:35:20 tse.sh[298675]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 06 18:35:20 tse.sh[298675]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 06 18:35:20 tse.sh[298675]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
Oct 06 18:35:20 tse.sh[298675]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 06 18:35:20 tse.sh[298675]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 06 18:35:20 tse.sh[298675]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 06 18:35:20 tse.sh[298675]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 06 18:35:20 tse.sh[298675]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 06 18:35:20 tse.sh[298675]:         at java.lang.Thread.run(Thread.java:748)

Besteht da vielleicht noch ein Problem?

Viele Grüße
André

misery · Beitrag von **misery** » Mi 7. Okt 2020, 15:55

Moin Stefan,

mit der 2.0.7 taucht das TSE-Problem noch immer auf. Hier mal das Log mit level 2 von heute. Ich habe die Stellen, in der sich das Checken immer wiederholt hat mit "..." gekürzt.

Code: Alles auswählen

Oct 07 08:00:03 systemd[1]: Stopping TSE...
Oct 07 08:00:03 systemd[1]: tse.service: Succeeded.
Oct 07 08:00:03 systemd[1]: Stopped TSE.
Oct 07 08:00:03 systemd[1]: Started TSE.
Oct 07 08:00:04 tse.sh[302258]: TSEConnectors kann folgende Parameter verarbeiten:
Oct 07 08:00:04 tse.sh[302258]:    pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
Oct 07 08:00:04 tse.sh[302258]:    port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
Oct 07 08:00:04 tse.sh[302258]:    mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
Oct 07 08:00:04 tse.sh[302258]:    mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
Oct 07 08:00:04 tse.sh[302258]:    loglevel=number  0: nur relevante Infos, 1: Signierungen, 2: umfangreiches Logging.
Oct 07 08:00:04 tse.sh[302258]: Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
Oct 07 08:00:04 tse.sh[302258]:    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
Oct 07 08:00:04 tse.sh[302258]: Start TSEConnector mit diesen Parametern:
Oct 07 08:00:04 tse.sh[302258]:   pass=      XXX
Oct 07 08:00:04 tse.sh[302258]:   port=      6666
Oct 07 08:00:04 tse.sh[302258]:   supplier=  swissbit
Oct 07 08:00:04 tse.sh[302258]:   mountpoint=/mnt/tse
Oct 07 08:00:04 tse.sh[302258]:   mode=      normal
Oct 07 08:00:04 tse.sh[302258]:   loglevel=  2
Oct 07 08:00:04 tse.sh[302258]: TSE seems to be mounted
Oct 07 08:00:04 tse.sh[302258]: Sync Time = 1800 seconds (30 minutes)
Oct 07 08:00:04 tse.sh[302258]: Abgeschlossen Startup des TSEConnectors
Oct 07 10:38:41 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:38:42 tse.sh[302258]: isCheckUseful = true: current = 1602059922, lastSucces=0
Oct 07 10:38:42 tse.sh[302258]: Check for TSE Status and availability
Oct 07 10:38:42 tse.sh[302258]: TSE has no valid time. Set time now.
Oct 07 10:38:46 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:38:46 tse.sh[302258]: isCheckUseful = false: current = 1602059926, lastSucces=1602059923
...

Oct 07 10:39:06 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:39:06 tse.sh[302258]: isCheckUseful = false: current = 1602059946, lastSucces=1602059923
Oct 07 10:39:06 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:39:09 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Earl Grey\";1.70"}
Oct 07 10:39:09 tse.sh[302258]: Starte Signierung
Oct 07 10:39:10 tse.sh[302258]:   Last Transaction Details
Oct 07 10:39:10 tse.sh[302258]:   Log Time: 1602059949
Oct 07 10:39:10 tse.sh[302258]:   Signature Counter: 811
Oct 07 10:39:10 tse.sh[302258]:   Transaction Number: 320
Oct 07 10:39:10 tse.sh[302258]:   Log Message Length: 237
Oct 07 10:39:10 tse.sh[302258]:   Process Data Length: 18
Oct 07 10:39:10 tse.sh[302258]:   Log Message:
Oct 07 10:39:10 tse.sh[302258]: 3081EA020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F5244318212313B224561726C2047726579223B312E3730830D42657374656C6C756E672D56318502014004207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202032B02045F7D7EAD04600CAC67546DE2186E1FC0336683B48B6A1CF15A318B3C962A2AA7D59F9B841C5DE76B8BE8D27DCFE2DA3ED84BE986C92B47C795ABEACC0DF6EB970D2CA530C213A42C4ADE3F41DFCEDB9B763F80C6D581B9AE6022B9B09F572DC9955FF0CD5BD1
Oct 07 10:39:10 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:39:10 tse.sh[302258]:   Signature: DKxnVG3iGG4fwDNmg7SLahzxWjGLPJYqKqfVn5uEHF3na4vo0n3P4to+2EvphskrR8eVq+rMDfbrlw0spTDCE6QsSt4/Qd/O25t2P4DG1YG5rmAiubCfVy3JlV/wzVvR
Oct 07 10:39:10 tse.sh[302258]:   TSESerialNo: [B@4b504184
Oct 07 10:39:10 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:39:11 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:39:11 tse.sh[302258]: isCheckUseful = false: current = 1602059951, lastSucces=1602059923
Oct 07 10:39:11 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:39:16 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:39:16 tse.sh[302258]: isCheckUseful = false: current = 1602059956, lastSucces=1602059923
...
Oct 07 10:40:12 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:40:12 tse.sh[302258]: isCheckUseful = false: current = 1602060012, lastSucces=1602059923
Oct 07 10:40:12 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:40:12 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^1.70_0.00_0.00_0.00_0.00^1.70:Bar"}
Oct 07 10:40:12 tse.sh[302258]: Starte Signierung
Oct 07 10:40:13 tse.sh[302258]:   Last Transaction Details
Oct 07 10:40:13 tse.sh[302258]:   Log Time: 1602060013
Oct 07 10:40:13 tse.sh[302258]:   Signature Counter: 813
Oct 07 10:40:13 tse.sh[302258]:   Transaction Number: 321
Oct 07 10:40:13 tse.sh[302258]:   Log Message Length: 260
Oct 07 10:40:13 tse.sh[302258]:   Process Data Length: 39
Oct 07 10:40:13 tse.sh[302258]:   Log Message:
Oct 07 10:40:13 tse.sh[302258]: 30820100020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822742656C65675E312E37305F302E30305F302E30305F302E30305F302E30305E312E37303A426172830E4B617373656E62656C65672D56318502014104207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202032D02045F7D7EED04604C3AF70A50070E47F052681FE5932BA1DFDDA9C541879649DC6728C85928E5D3411371B55C54FEEF7F7648255C4BA16352987D7611DAA8DD3AF5C191857F9A05AF7AC29B5FFFF131166205E0B978543C167996096BBAA85FAB4C425824550F4B
Oct 07 10:40:13 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:40:13 tse.sh[302258]:   Signature: TDr3ClAHDkfwUmgf5ZMrod/dqcVBh5ZJ3GcoyFko5dNBE3G1XFT+7392SCVcS6FjUph9dhHaqN069cGRhX+aBa96wptf//ExFmIF4Ll4VDwWeZYJa7qoX6tMQlgkVQ9L
Oct 07 10:40:13 tse.sh[302258]:   TSESerialNo: [B@3d1cbd35
Oct 07 10:40:13 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:40:14 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:40:14 tse.sh[302258]: isCheckUseful = false: current = 1602060014, lastSucces=1602059923
...
Oct 07 10:40:35 tse.sh[302258]: isCheckUseful = false: current = 1602060035, lastSucces=1602059923
Oct 07 10:40:35 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:40:35 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"-1;\"Earl Grey\";1.70"}
Oct 07 10:40:35 tse.sh[302258]: Starte Signierung
Oct 07 10:40:36 tse.sh[302258]:   Last Transaction Details
Oct 07 10:40:36 tse.sh[302258]:   Log Time: 1602060037
Oct 07 10:40:36 tse.sh[302258]:   Signature Counter: 815
Oct 07 10:40:36 tse.sh[302258]:   Transaction Number: 322
Oct 07 10:40:36 tse.sh[302258]:   Log Message Length: 238
Oct 07 10:40:36 tse.sh[302258]:   Process Data Length: 19
Oct 07 10:40:36 tse.sh[302258]:   Log Message:
Oct 07 10:40:36 tse.sh[302258]: 3081EB020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F52443182132D313B224561726C2047726579223B312E3730830D42657374656C6C756E672D56318502014204207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202032F02045F7D7F050460012EEB8A9CB3122C0968FB4F9E602BA8421A0A21FA51AE6AE022F33F491E6070E3A07F2E37696AADFFAC48AF5A9FDC331500F2D8D59E545AA328F0623F8344CB434498968F04BCED2F3574D096E1BE2D7F07484C001798A49128A4ADB0F80152
Oct 07 10:40:36 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:40:36 tse.sh[302258]:   Signature: AS7ripyzEiwJaPtPnmArqEIaCiH6Ua5q4CLzP0keYHDjoH8uN2lqrf+sSK9an9wzFQDy2NWeVFqjKPBiP4NEy0NEmJaPBLztLzV00Jbhvi1/B0hMABeYpJEopK2w+AFS
Oct 07 10:40:36 tse.sh[302258]:   TSESerialNo: [B@4c522c4b
Oct 07 10:40:36 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:40:36 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^-1.70_0.00_0.00_0.00_0.00^-1.70:Bar"}
Oct 07 10:40:36 tse.sh[302258]: Starte Signierung
Oct 07 10:40:36 tse.sh[302258]:   Last Transaction Details
Oct 07 10:40:36 tse.sh[302258]:   Log Time: 1602060037
Oct 07 10:40:36 tse.sh[302258]:   Signature Counter: 817
Oct 07 10:40:36 tse.sh[302258]:   Transaction Number: 323
Oct 07 10:40:36 tse.sh[302258]:   Log Message Length: 262
Oct 07 10:40:36 tse.sh[302258]:   Process Data Length: 41
Oct 07 10:40:36 tse.sh[302258]:   Log Message:
Oct 07 10:40:36 tse.sh[302258]: 30820102020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822942656C65675E2D312E37305F302E30305F302E30305F302E30305F302E30305E2D312E37303A426172830E4B617373656E62656C65672D56318502014304207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033102045F7D7F050460146755C9B9936299CE1A457BBD970C92286D34977EF51710CDCBB8771B5326DAFBD23982083432C555FF73EA238E18E33651C712C788D335FFBA85E697944571ADF411487AE25AEA6B442DE328EBA1175DF8035743B9FC7BE46C8AA48876CE1B
Oct 07 10:40:36 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:40:36 tse.sh[302258]:   Signature: FGdVybmTYpnOGkV7vZcMkihtNJd+9RcQzcu4dxtTJtr70jmCCDQyxVX/c+ojjhjjNlHHEseI0zX/uoXml5RFca30EUh64lrqa0Qt4yjroRdd+ANXQ7n8e+RsiqSIds4b
Oct 07 10:40:36 tse.sh[302258]:   TSESerialNo: [B@7c6a3265
Oct 07 10:40:36 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:40:38 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:40:38 tse.sh[302258]: isCheckUseful = false: current = 1602060038, lastSucces=1602059923
Oct 07 10:40:38 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:40:43 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:40:43 tse.sh[302258]: isCheckUseful = false: current = 1602060043, lastSucces=1602059923

...

Oct 07 10:48:30 tse.sh[302258]: isCheckUseful = false: current = 1602060510, lastSucces=1602059923
Oct 07 10:48:30 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:48:35 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:48:35 tse.sh[302258]: isCheckUseful = false: current = 1602060515, lastSucces=1602059923
Oct 07 10:48:35 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:48:38 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Cappuccino\";2.20"}
Oct 07 10:48:38 tse.sh[302258]: Starte Signierung
Oct 07 10:48:39 tse.sh[302258]:   Last Transaction Details
Oct 07 10:48:39 tse.sh[302258]:   Log Time: 1602060528
Oct 07 10:48:39 tse.sh[302258]:   Signature Counter: 819
Oct 07 10:48:39 tse.sh[302258]:   Transaction Number: 324
Oct 07 10:48:39 tse.sh[302258]:   Log Message Length: 238
Oct 07 10:48:39 tse.sh[302258]:   Process Data Length: 19
Oct 07 10:48:39 tse.sh[302258]:   Log Message:
Oct 07 10:48:39 tse.sh[302258]: 3081EB020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F5244318213313B2243617070756363696E6F223B322E3230830D42657374656C6C756E672D56318502014404207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033302045F7D80F0046029ABBA1005750F05195006E4443794CF971F68126E7CFF8656D3CACE5A7D9150054F8A49CB8343452EB568D21BA8ED612FC8AA4BCEFF695A6EE40F163A24AEFD54127C99A5BDF7299C515A9DF145054F3FD278299A389BD9A7CDA06D74226EF8
Oct 07 10:48:39 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:48:39 tse.sh[302258]:   Signature: Kau6EAV1DwUZUAbkRDeUz5cfaBJufP+GVtPKzlp9kVAFT4pJy4NDRS61aNIbqO1hL8iqS87/aVpu5A8WOiSu/VQSfJmlvfcpnFFanfFFBU8/0ngpmjib2afNoG10Im74
Oct 07 10:48:39 tse.sh[302258]:   TSESerialNo: [B@2ac74909
Oct 07 10:48:39 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:48:40 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:48:40 tse.sh[302258]: isCheckUseful = false: current = 1602060520, lastSucces=1602059923
Oct 07 10:48:40 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:48:44 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^2.20_0.00_0.00_0.00_0.00^2.20:Bar"}
Oct 07 10:48:44 tse.sh[302258]: Starte Signierung
Oct 07 10:48:44 tse.sh[302258]:   Last Transaction Details
Oct 07 10:48:44 tse.sh[302258]:   Log Time: 1602060534
Oct 07 10:48:44 tse.sh[302258]:   Signature Counter: 821
Oct 07 10:48:44 tse.sh[302258]:   Transaction Number: 325
Oct 07 10:48:44 tse.sh[302258]:   Log Message Length: 260
Oct 07 10:48:44 tse.sh[302258]:   Process Data Length: 39
Oct 07 10:48:44 tse.sh[302258]:   Log Message:
Oct 07 10:48:44 tse.sh[302258]: 30820100020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822742656C65675E322E32305F302E30305F302E30305F302E30305F302E30305E322E32303A426172830E4B617373656E62656C65672D56318502014504207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033502045F7D80F6046045EEDD1D87001F4CA4C27EDEC67DA33222A811919C2F66352D83C2F1C8B54F065E98EE5CEC7478AF5F5DF8C0024402B70B411268300214383E4C1036524F5A925CC2911A76F65320E3E617F7FB8A7065768165F8EAD641AA9F3E9537AA383BAE
Oct 07 10:48:44 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:48:44 tse.sh[302258]:   Signature: Re7dHYcAH0ykwn7exn2jMiKoEZGcL2Y1LYPC8ci1TwZemO5c7HR4r19d+MACRAK3C0ESaDACFDg+TBA2Uk9aklzCkRp29lMg4+YX9/uKcGV2gWX46tZBqp8+lTeqODuu
Oct 07 10:48:44 tse.sh[302258]:   TSESerialNo: [B@113f5cdd
Oct 07 10:48:44 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:48:45 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:48:45 tse.sh[302258]: isCheckUseful = false: current = 1602060525, lastSucces=1602059923
Oct 07 10:48:45 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 10:48:57 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:48:57 tse.sh[302258]: isCheckUseful = false: current = 1602060537, lastSucces=1602059923
Oct 07 10:48:57 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 10:49:00 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"-1;\"Cappuccino\";2.20"}
Oct 07 10:49:00 tse.sh[302258]: Starte Signierung
Oct 07 10:49:01 tse.sh[302258]:   Last Transaction Details
Oct 07 10:49:01 tse.sh[302258]:   Log Time: 1602060551
Oct 07 10:49:01 tse.sh[302258]:   Signature Counter: 823
Oct 07 10:49:01 tse.sh[302258]:   Transaction Number: 326
Oct 07 10:49:01 tse.sh[302258]:   Log Message Length: 239
Oct 07 10:49:01 tse.sh[302258]:   Process Data Length: 20
Oct 07 10:49:01 tse.sh[302258]:   Log Message:
Oct 07 10:49:01 tse.sh[302258]: 3081EC020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F52443182142D313B2243617070756363696E6F223B322E3230830D42657374656C6C756E672D56318502014604207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033702045F7D810704606ED8A36E674566B7296E88493635B3195478C421EFF23AA32334D61B2C1CBD1EC89A7C83CA5B662BEB28A33820F3F88107EA9475726EF3428FB973D661D67C0A0167C0A4C7F4D4B4E304C94DD56D9A26042533C3DE399F9A13CFB1691F16CC36
Oct 07 10:49:01 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:49:01 tse.sh[302258]:   Signature: btijbmdFZrcpbohJNjWzGVR4xCHv8jqjIzTWGywcvR7ImnyDyltmK+soozgg8/iBB+qUdXJu80KPuXPWYdZ8CgFnwKTH9NS04wTJTdVtmiYEJTPD3jmfmhPPsWkfFsw2
Oct 07 10:49:01 tse.sh[302258]:   TSESerialNo: [B@245e45f7
Oct 07 10:49:01 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:49:01 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^-2.20_0.00_0.00_0.00_0.00^-2.20:Bar"}
Oct 07 10:49:01 tse.sh[302258]: Starte Signierung
Oct 07 10:49:01 tse.sh[302258]:   Last Transaction Details
Oct 07 10:49:01 tse.sh[302258]:   Log Time: 1602060551
Oct 07 10:49:01 tse.sh[302258]:   Signature Counter: 825
Oct 07 10:49:01 tse.sh[302258]:   Transaction Number: 327
Oct 07 10:49:01 tse.sh[302258]:   Log Message Length: 262
Oct 07 10:49:01 tse.sh[302258]:   Process Data Length: 41
Oct 07 10:49:01 tse.sh[302258]:   Log Message:
Oct 07 10:49:01 tse.sh[302258]: 30820102020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822942656C65675E2D322E32305F302E30305F302E30305F302E30305F302E30305E2D322E32303A426172830E4B617373656E62656C65672D56318502014704207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033902045F7D810704605C8096ADB38E2B8379A0CC46F1348B16FD30FD74212221A31E8478637FF1724999C5334B0AA6DD5CFA0433858B06BBE7467A69988E523B1FAB935151FFA8F45BA8BD63191EC3F8EF08C0E99DD1222FF36AF7C0D580A05E48500060EF3D5E19D3
Oct 07 10:49:01 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 10:49:01 tse.sh[302258]:   Signature: XICWrbOOK4N5oMxG8TSLFv0w/XQhIiGjHoR4Y3/xckmZxTNLCqbdXPoEM4WLBrvnRnppmI5SOx+rk1FR/6j0W6i9Yxkew/jvCMDpndEiL/Nq98DVgKBeSFAAYO89XhnT
Oct 07 10:49:01 tse.sh[302258]:   TSESerialNo: [B@41c43ff4
Oct 07 10:49:01 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 10:49:02 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 10:49:02 tse.sh[302258]: isCheckUseful = false: current = 1602060542, lastSucces=1602059923
Oct 07 10:49:02 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 11:08:40 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 11:08:45 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 11:08:45 tse.sh[302258]: isCheckUseful = true: current = 1602061725, lastSucces=1602059923
Oct 07 11:08:45 tse.sh[302258]: Check for TSE Status and availability
Oct 07 11:08:45 tse.sh[302258]: TSE has no valid self test. Start self test now.
Oct 07 11:08:45 tse.sh[302258]: Start Self Test
Oct 07 11:08:59 tse.sh[302258]: Completed Self Test
Oct 07 11:08:59 tse.sh[302258]: TSE has no valid time. Set time now.
Oct 07 11:08:59 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 11:08:59 tse.sh[302258]: isCheckUseful = false: current = 1602061739, lastSucces=1602061739
Oct 07 11:08:59 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 11:09:00 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 11:09:00 tse.sh[302258]: isCheckUseful = false: current = 1602061740, lastSucces=1602061739
...
Oct 07 11:29:38 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 13:13:13 tse.sh[302258]: isCheckUseful = true: current = 1602069193, lastSucces=1602061739
Oct 07 13:13:13 tse.sh[302258]: Check for TSE Status and availability
Oct 07 13:13:13 tse.sh[302258]: TSE has no valid time. Set time now.
Oct 07 13:13:18 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
...
Oct 07 13:13:30 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Milchkaffee\";2.40"}
Oct 07 13:13:30 tse.sh[302258]: Starte Signierung
Oct 07 13:13:30 tse.sh[302258]:   Last Transaction Details
Oct 07 13:13:30 tse.sh[302258]:   Log Time: 1602069209
Oct 07 13:13:30 tse.sh[302258]:   Signature Counter: 829
Oct 07 13:13:30 tse.sh[302258]:   Transaction Number: 328
Oct 07 13:13:30 tse.sh[302258]:   Log Message Length: 239
Oct 07 13:13:30 tse.sh[302258]:   Process Data Length: 20
Oct 07 13:13:30 tse.sh[302258]:   Log Message:
Oct 07 13:13:30 tse.sh[302258]: 3081EC020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F5244318214313B224D696C63686B6166666565223B322E3430830D42657374656C6C756E672D56318502014804207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033D02045F7DA2D90460721F0D2F04068B4F4FFA63E044BD2145BAFA47781274F90F88560C89B0CB8BCD56EA0B999AD9F4B820E3DFD2E692A47B59B551968794D66180E34B9AF694AC1A0D9240F44A81D44AF032D4F61D21DEEB5A2C2095ED6864CAC7E3818B523CF8B1
Oct 07 13:13:30 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 13:13:30 tse.sh[302258]:   Signature: ch8NLwQGi09P+mPgRL0hRbr6R3gSdPkPiFYMibDLi81W6guZmtn0uCDj39LmkqR7WbVRloeU1mGA40ua9pSsGg2SQPRKgdRK8DLU9h0h3utaLCCV7WhkysfjgYtSPPix
Oct 07 13:13:30 tse.sh[302258]:   TSESerialNo: [B@1c19ae87
Oct 07 13:13:30 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 13:13:33 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 13:13:33 tse.sh[302258]: isCheckUseful = false: current = 1602069213, lastSucces=1602069194
Oct 07 13:13:33 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 13:16:20 tse.sh[302258]: isCheckUseful = false: current = 1602069380, lastSucces=1602069194
Oct 07 13:16:20 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 13:16:25 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 13:16:25 tse.sh[302258]: isCheckUseful = false: current = 1602069385, lastSucces=1602069194
Oct 07 13:16:25 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 13:16:26 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^2.40_0.00_0.00_0.00_0.00^2.40:Bar"}
Oct 07 13:16:26 tse.sh[302258]: Starte Signierung
Oct 07 13:16:27 tse.sh[302258]:   Last Transaction Details
Oct 07 13:16:27 tse.sh[302258]:   Log Time: 1602069389
Oct 07 13:16:27 tse.sh[302258]:   Signature Counter: 831
Oct 07 13:16:27 tse.sh[302258]:   Transaction Number: 329
Oct 07 13:16:27 tse.sh[302258]:   Log Message Length: 260
Oct 07 13:16:27 tse.sh[302258]:   Process Data Length: 39
Oct 07 13:16:27 tse.sh[302258]:   Log Message:
Oct 07 13:16:27 tse.sh[302258]: 30820100020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822742656C65675E322E34305F302E30305F302E30305F302E30305F302E30305E322E34303A426172830E4B617373656E62656C65672D56318502014904207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202033F02045F7DA38D04606124F2F03F70DD5A2C9E13B8BEFF6DCBA370312702A7C86EB852BF6685A2A1AE78F7E6CA8263012B38577ACB7054E1A3361808562D50BBAC7C4494C0B5D1A4E8226871F357AAFD32E84307D569ECD3F1264A702088D1B64F506FE87924F15789
Oct 07 13:16:27 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 13:16:27 tse.sh[302258]:   Signature: YSTy8D9w3VosnhO4vv9ty6NwMScCp8huuFK/ZoWioa549+bKgmMBKzhXestwVOGjNhgIVi1Qu6x8RJTAtdGk6CJocfNXqv0y6EMH1Wns0/EmSnAgiNG2T1Bv6Hkk8VeJ
Oct 07 13:16:27 tse.sh[302258]:   TSESerialNo: [B@6c067053
Oct 07 13:16:27 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 13:16:28 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
...
Oct 07 14:13:18 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:13:18 tse.sh[302258]: isCheckUseful = false: current = 1602072798, lastSucces=1602070998
Oct 07 14:13:18 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:13:23 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:13:23 tse.sh[302258]: isCheckUseful = true: current = 1602072803, lastSucces=1602070998
Oct 07 14:13:23 tse.sh[302258]: Check for TSE Status and availability
Oct 07 14:13:23 tse.sh[302258]: TSE has no valid time. Set time now.
Oct 07 14:13:28 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:13:28 tse.sh[302258]: isCheckUseful = false: current = 1602072808, lastSucces=1602072803
Oct 07 14:13:28 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 14:24:13 tse.sh[302258]: isCheckUseful = false: current = 1602073453, lastSucces=1602072803
Oct 07 14:24:13 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:24:18 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:24:18 tse.sh[302258]: isCheckUseful = false: current = 1602073458, lastSucces=1602072803
Oct 07 14:24:18 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:24:20 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Eiskaffee\";3.90\n1;\"Gummib\u00e4rchen Becher\";3.70\n1;\"Gummib\u00e4rchen Becher\";3.70"}
Oct 07 14:24:20 tse.sh[302258]: Starte Signierung
Oct 07 14:24:21 tse.sh[302258]:   Last Transaction Details
Oct 07 14:24:21 tse.sh[302258]:   Log Time: 1602073472
Oct 07 14:24:21 tse.sh[302258]:   Signature Counter: 834
Oct 07 14:24:21 tse.sh[302258]:   Transaction Number: 330
Oct 07 14:24:21 tse.sh[302258]:   Log Message Length: 317
Oct 07 14:24:21 tse.sh[302258]:   Process Data Length: 97
Oct 07 14:24:21 tse.sh[302258]:   Log Message:
Oct 07 14:24:21 tse.sh[302258]: 30820139020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F5244318261313B224569736B6166666565223B332E39300A313B224569736B6166666565223B332E39300A313B2247756D6D6962C3A4726368656E20426563686572223B332E37300A313B2247756D6D6962C3A4726368656E20426563686572223B332E3730830D42657374656C6C756E672D56318502014A04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202034202045F7DB38004603F8E99761ABE335E486C6B8A5BED4A28622BE72524DFEEA98E98A0770FBEB28FFED84C4C3CA1B2DA75512EA54ED03F025B354902A9372AC173D3211A99EFCBA1EC16647BEB526F771FAF478AB049DC8D5226BF269A3FB4759BFF6342F87BA1FA
Oct 07 14:24:21 tse.sh[302258]:   SigAlg: ecdsa-plain-SHA384
Oct 07 14:24:21 tse.sh[302258]:   Signature: P46Zdhq+M15IbGuKW+1KKGIr5yUk3+6pjpigdw++so/+2ExMPKGy2nVRLqVO0D8CWzVJAqk3KsFz0yEame/LoewWZHvrUm93H69HirBJ3I1SJr8mmj+0dZv/Y0L4e6H6
Oct 07 14:24:21 tse.sh[302258]:   TSESerialNo: [B@381d67fa
Oct 07 14:24:21 tse.sh[302258]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 14:24:23 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:24:23 tse.sh[302258]: isCheckUseful = false: current = 1602073463, lastSucces=1602072803
Oct 07 14:24:23 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 14:43:23 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:43:28 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:43:28 tse.sh[302258]: isCheckUseful = true: current = 1602074608, lastSucces=1602072803
Oct 07 14:43:28 tse.sh[302258]: Check for TSE Status and availability
Oct 07 14:43:33 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:43:33 tse.sh[302258]: isCheckUseful = false: current = 1602074613, lastSucces=1602074608
...
Oct 07 14:49:03 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:49:05 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:49:05 tse.sh[302258]: isCheckUseful = false: current = 1602074945, lastSucces=1602074608
Oct 07 14:49:05 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:49:09 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 14:49:09 tse.sh[302258]: Starte Signierung
Oct 07 14:49:09 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 14:49:09 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 14:49:09 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 14:49:09 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 14:49:09 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Oct 07 14:49:09 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 14:49:09 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 14:49:09 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 14:49:09 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 14:49:09 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 14:49:09 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 14:49:15 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:49:15 tse.sh[302258]: isCheckUseful = false: current = 1602074955, lastSucces=1602074608
Oct 07 14:49:15 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:49:20 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:49:20 tse.sh[302258]: isCheckUseful = false: current = 1602074960, lastSucces=1602074608
Oct 07 14:49:20 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:49:20 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 14:49:20 tse.sh[302258]: Starte Signierung
Oct 07 14:49:20 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 14:49:20 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 14:49:20 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 14:49:20 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 14:49:20 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Oct 07 14:49:20 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 14:49:20 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 14:49:20 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 14:49:20 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 14:49:20 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 14:49:20 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 14:52:48 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:52:48 tse.sh[302258]: isCheckUseful = false: current = 1602075168, lastSucces=1602074608
Oct 07 14:52:48 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:52:50 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:52:50 tse.sh[302258]: isCheckUseful = false: current = 1602075170, lastSucces=1602074608
Oct 07 14:52:50 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 14:53:30 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:53:30 tse.sh[302258]: isCheckUseful = false: current = 1602075210, lastSucces=1602074608
Oct 07 14:53:30 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:53:35 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 14:53:35 tse.sh[302258]: Starte Signierung
Oct 07 14:53:35 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 14:53:35 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 14:53:35 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 14:53:35 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 14:53:35 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Oct 07 14:53:35 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 14:53:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 14:53:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 14:53:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 14:53:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 14:53:35 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 14:53:36 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:53:36 tse.sh[302258]: isCheckUseful = false: current = 1602075216, lastSucces=1602074608
Oct 07 14:53:36 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 14:54:30 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:54:35 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:54:35 tse.sh[302258]: isCheckUseful = false: current = 1602075275, lastSucces=1602074608
Oct 07 14:54:35 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:54:35 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 14:54:35 tse.sh[302258]: Starte Signierung
Oct 07 14:54:35 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 14:54:35 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 14:54:35 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 14:54:35 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 14:54:35 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Oct 07 14:54:35 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 14:54:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 14:54:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 14:54:35 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 14:54:35 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 14:54:35 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 14:54:40 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:54:40 tse.sh[302258]: isCheckUseful = false: current = 1602075280, lastSucces=1602074608
Oct 07 14:54:40 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 14:56:05 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:56:05 tse.sh[302258]: isCheckUseful = false: current = 1602075365, lastSucces=1602074608
Oct 07 14:56:05 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 14:56:06 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 14:56:06 tse.sh[302258]: Starte Signierung
Oct 07 14:56:06 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 14:56:06 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 14:56:06 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 14:56:06 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 14:56:06 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Oct 07 14:56:06 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 14:56:06 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 14:56:06 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 14:56:06 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 14:56:06 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 14:56:06 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 14:56:10 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 14:56:10 tse.sh[302258]: isCheckUseful = false: current = 1602075370, lastSucces=1602074608
Oct 07 14:56:10 tse.sh[302258]: Ignore Check request - last check not that far in the past
...
Oct 07 15:08:15 tse.sh[302258]: isCheckUseful = false: current = 1602076095, lastSucces=1602074608
Oct 07 15:08:15 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 15:08:18 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eisverkauf ausser Haus\";6.90"}
Oct 07 15:08:18 tse.sh[302258]: Starte Signierung
Oct 07 15:08:18 tse.sh[302258]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct 07 15:08:18 tse.sh[302258]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct 07 15:08:18 tse.sh[302258]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct 07 15:08:18 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct 07 15:08:18 tse.sh[302258]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
Oct 07 15:08:18 tse.sh[302258]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct 07 15:08:18 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
Oct 07 15:08:18 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
Oct 07 15:08:18 tse.sh[302258]:         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
Oct 07 15:08:18 tse.sh[302258]:         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
Oct 07 15:08:18 tse.sh[302258]:         at java.lang.Thread.run(Thread.java:748)
Oct 07 15:08:54 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:08:54 tse.sh[302258]: isCheckUseful = false: current = 1602076134, lastSucces=1602074608
Oct 07 15:08:54 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 15:08:55 tse.sh[302258]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:08:55 tse.sh[302258]: isCheckUseful = false: current = 1602076135, lastSucces=1602074608
Oct 07 15:08:55 tse.sh[302258]: Ignore Check request - last check not that far in the past
Oct 07 15:11:31 systemd[1]: Stopping TSE...
Oct 07 15:11:32 systemd[1]: tse.service: Succeeded.
Oct 07 15:11:32 systemd[1]: Stopped TSE.
Oct 07 15:11:32 systemd[1]: Started TSE.
Oct 07 15:11:32 tse.sh[303809]: TSEConnectors kann folgende Parameter verarbeiten:
Oct 07 15:11:32 tse.sh[303809]:    pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
Oct 07 15:11:32 tse.sh[303809]:    port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
Oct 07 15:11:32 tse.sh[303809]:    mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
Oct 07 15:11:32 tse.sh[303809]:    mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
Oct 07 15:11:32 tse.sh[303809]:    loglevel=number  0: nur relevante Infos, 1: Signierungen, 2: umfangreiches Logging.
Oct 07 15:11:32 tse.sh[303809]: Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
Oct 07 15:11:32 tse.sh[303809]:    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
Oct 07 15:11:32 tse.sh[303809]: Start TSEConnector mit diesen Parametern:
Oct 07 15:11:32 tse.sh[303809]:   pass=      XXX
Oct 07 15:11:32 tse.sh[303809]:   port=      6666
Oct 07 15:11:32 tse.sh[303809]:   supplier=  swissbit
Oct 07 15:11:32 tse.sh[303809]:   mountpoint=/mnt/tse
Oct 07 15:11:32 tse.sh[303809]:   mode=      normal
Oct 07 15:11:32 tse.sh[303809]:   loglevel=  2
Oct 07 15:11:32 tse.sh[303809]: TSE seems to be mounted
Oct 07 15:11:32 tse.sh[303809]: Sync Time = 1800 seconds (30 minutes)
Oct 07 15:11:32 tse.sh[303809]: Abgeschlossen Startup des TSEConnectors
Oct 07 15:12:13 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:12:14 tse.sh[303809]: isCheckUseful = true: current = 1602076334, lastSucces=0
Oct 07 15:12:14 tse.sh[303809]: Check for TSE Status and availability
Oct 07 15:12:14 tse.sh[303809]: TSE has no valid time. Set time now.
Oct 07 15:12:18 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:12:18 tse.sh[303809]: isCheckUseful = false: current = 1602076338, lastSucces=1602076334
Oct 07 15:12:18 tse.sh[303809]: Ignore Check request - last check not that far in the past
...
Oct 07 15:12:43 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^7.80_7.40_0.00_0.00_0.00^15.20:Bar"}
Oct 07 15:12:43 tse.sh[303809]: Starte Signierung
Oct 07 15:12:44 tse.sh[303809]:   Last Transaction Details
Oct 07 15:12:44 tse.sh[303809]:   Log Time: 1602076364
Oct 07 15:12:44 tse.sh[303809]:   Signature Counter: 837
Oct 07 15:12:44 tse.sh[303809]:   Transaction Number: 331
Oct 07 15:12:44 tse.sh[303809]:   Log Message Length: 261
Oct 07 15:12:44 tse.sh[303809]:   Process Data Length: 40
Oct 07 15:12:44 tse.sh[303809]:   Log Message:
Oct 07 15:12:44 tse.sh[303809]: 30820101020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822842656C65675E372E38305F372E34305F302E30305F302E30305F302E30305E31352E32303A426172830E4B617373656E62656C65672D56318502014B04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202034502045F7DBECC046040DAE4857DA79CAE95EF7EEA0411748F22EE52B3341462D8A72655FD72A86EEECEA7BA18A89927DFDD8F57844C73BFD63AB0961A1A3BD612D2AFB28E9D9A7A613B7C0D1D3F6423E6E0C3A787613B175B8CCCEA45BECCF254997083249C77D0DB
Oct 07 15:12:44 tse.sh[303809]:   SigAlg: ecdsa-plain-SHA384
Oct 07 15:12:44 tse.sh[303809]:   Signature: QNrkhX2nnK6V737qBBF0jyLuUrM0FGLYpyZV/XKobu7Op7oYqJkn392PV4RMc7/WOrCWGho71hLSr7KOnZp6YTt8DR0/ZCPm4MOnh2E7F1uMzOpFvszyVJlwgyScd9Db
Oct 07 15:12:44 tse.sh[303809]:   TSESerialNo: [B@4b504184
Oct 07 15:12:44 tse.sh[303809]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 15:12:44 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:12:44 tse.sh[303809]: isCheckUseful = false: current = 1602076364, lastSucces=1602076334
Oct 07 15:12:44 tse.sh[303809]: Ignore Check request - last check not that far in the past
...
Oct 07 15:13:00 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:13:00 tse.sh[303809]: isCheckUseful = false: current = 1602076380, lastSucces=1602076334
Oct 07 15:13:00 tse.sh[303809]: Ignore Check request - last check not that far in the past
Oct 07 15:13:04 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signorders","value":"1;\"Eisverkauf ausser Haus\";6.90"}
Oct 07 15:13:04 tse.sh[303809]: Starte Signierung
Oct 07 15:13:04 tse.sh[303809]:   Last Transaction Details
Oct 07 15:13:04 tse.sh[303809]:   Log Time: 1602076385
Oct 07 15:13:04 tse.sh[303809]:   Signature Counter: 839
Oct 07 15:13:04 tse.sh[303809]:   Transaction Number: 332
Oct 07 15:13:04 tse.sh[303809]:   Log Message Length: 250
Oct 07 15:13:04 tse.sh[303809]:   Process Data Length: 31
Oct 07 15:13:04 tse.sh[303809]:   Log Message:
Oct 07 15:13:04 tse.sh[303809]: 3081F7020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431821F313B224569737665726B617566206175737365722048617573223B362E3930830D42657374656C6C756E672D56318502014C04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202034702045F7DBEE1046068A3AA446F105FB62826A60C6064C721B9A54F406F946536C3225F2E39474A123112C1642DFD1B946ADB23B7B7EBD0E64975ABB9B15A77F6845920F1093800BB60B871C784AE3126F14E405F782AD7A662AEAF72EED07EADF3CE777C36B28C4A
Oct 07 15:13:04 tse.sh[303809]:   SigAlg: ecdsa-plain-SHA384
Oct 07 15:13:04 tse.sh[303809]:   Signature: aKOqRG8QX7YoJqYMYGTHIbmlT0BvlGU2wyJfLjlHShIxEsFkLf0blGrbI7e369DmSXWrubFad/aEWSDxCTgAu2C4cceErjEm8U5AX3gq16Zirq9y7tB+rfPOd3w2soxK
Oct 07 15:13:04 tse.sh[303809]:   TSESerialNo: [B@478dd771
Oct 07 15:13:04 tse.sh[303809]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 15:13:05 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:13:05 tse.sh[303809]: isCheckUseful = false: current = 1602076385, lastSucces=1602076334
Oct 07 15:13:05 tse.sh[303809]: Ignore Check request - last check not that far in the past
Oct 07 15:13:09 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"signnormalbill","value":"Beleg^0.00_6.90_0.00_0.00_0.00^6.90:Unbar"}
Oct 07 15:13:09 tse.sh[303809]: Starte Signierung
Oct 07 15:13:10 tse.sh[303809]:   Last Transaction Details
Oct 07 15:13:10 tse.sh[303809]:   Log Time: 1602076390
Oct 07 15:13:10 tse.sh[303809]:   Signature Counter: 841
Oct 07 15:13:10 tse.sh[303809]:   Transaction Number: 333
Oct 07 15:13:10 tse.sh[303809]:   Log Message Length: 262
Oct 07 15:13:10 tse.sh[303809]:   Process Data Length: 41
Oct 07 15:13:10 tse.sh[303809]:   Log Message:
Oct 07 15:13:10 tse.sh[303809]: 30820102020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822942656C65675E302E30305F362E39305F302E30305F302E30305F302E30305E362E39303A556E626172830E4B617373656E62656C65672D56318502014D04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F000701010401040202034902045F7DBEE604603F1F17C56D025196D1F6945FFA590219924BCCFD4F1042DF0BC2E52057E9EF16BCBB4D160F84A46448151A6398558E3628660E224BB3A840A479E68A0E3907D3DF628637496F25E1ED521A4563F55ECDEF7D8305EEFE1E51C4F913FDBD5D7BB6
Oct 07 15:13:10 tse.sh[303809]:   SigAlg: ecdsa-plain-SHA384
Oct 07 15:13:10 tse.sh[303809]:   Signature: Px8XxW0CUZbR9pRf+lkCGZJLzP1PEELfC8LlIFfp7xa8u00WD4SkZEgVGmOYVY42KGYOIkuzqECkeeaKDjkH099ihjdJbyXh7VIaRWP1Xs3vfYMF7v4eUcT5E/29XXu2
Oct 07 15:13:10 tse.sh[303809]:   TSESerialNo: [B@4502bfee
Oct 07 15:13:10 tse.sh[303809]:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Oct 07 15:13:10 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}
Oct 07 15:13:10 tse.sh[303809]: isCheckUseful = false: current = 1602076390, lastSucces=1602076334
Oct 07 15:13:10 tse.sh[303809]: Ignore Check request - last check not that far in the past
Oct 07 15:13:11 tse.sh[303809]: Transferred POST-Data: {"pass":"XXX","pin":[YYY],"clientid":"ORD1","cmd":"check"}

Beitrag von **pichel** » Mi 7. Okt 2020, 16:30

Danke für die Logs. Ich werde mir das in den nächsten Tagen anschauen, versprochen!!

os_user · Beitrag von **os_user** » Do 8. Okt 2020, 16:53

Hallo Herr Pichel,
auch in der neuen 2.0.7er-Version treten leider ähnliche Fehler der TSE auf. Was mich wundert ist, dass die Eigenschaft hasValidTime auch nach kurzer Laufzeit des Connectors im Admin wohl korrekt erkannt wird, aber keine Uhrzeit gesetzt wird:

Command: getinfo

Result: Self Test completed

Result: TseDescription: BSI-K-TR-0362
ceritificateExpirationDate: 2026-02-16 00:59:59
Created Signatures: XXXX
hasValidTime: false
Anzahl verbleibender Signaturen: XXXXXXXX

Im Syslog findet sich folgende Meldung:

java[7267]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
java[7267]: #011at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
java[7267]: #011at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
java[7267]: #011at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
java[7267]: #011at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
java[7267]: #011at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
java[7267]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
java[7267]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
java[7267]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
java[7267]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
java[7267]: #011at java.base/java.lang.Thread.run(Thread.java:834)

os_user · Beitrag von **os_user** » Do 8. Okt 2020, 17:44

Hallo Herr Pichel,
noch ein kurzes Update: In der neuen Version 2.0.7. tritt der Fehler aus dem vorherigen Post nun recht genau stündlich auf.

staebners · Beitrag von **staebners** » Do 8. Okt 2020, 18:44

Hallo.
Auch bei mir ähnliches Verhalten - auch 2.0.7

Nach etwa 1h bekomme ich Fehlermeldungen zum TSE-Connector und im Log sieht es so aus:

Code: Alles auswählen

Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at tseconnector.handler.RequestHandler.handle(RequestHandler.java:52)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Oct  8 18:35:11 POS-SRV1 tseconnector.bat[5359]: #011at java.base/java.lang.Thread.run(Thread.java:834)

Ich habe jetzt erst einmal die TSE deaktiviert - so läuft alles gut.

OrderSprinter-Forum

BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector

Re: BUG: TSE Connector