BUG: TSE Connector

Antwort erstellen

Bestätigungscode
Gib den Code genau so ein, wie du ihn siehst; Groß- und Kleinschreibung wird nicht unterschieden.
Smileys
:D :) ;) :( :o :shock: :? 8-) :lol: :x :P :oops: :cry: :evil: :twisted: :roll: :!: :?: :idea: :arrow: :| :mrgreen: :geek: :ugeek:

BBCode ist eingeschaltet
[img] ist eingeschaltet
[url] ist eingeschaltet
Smileys sind eingeschaltet

Die letzten Beiträge des Themas
   

Ansicht erweitern Die letzten Beiträge des Themas: BUG: TSE Connector

Re: BUG: TSE Connector

von pichel » Di 27. Okt 2020, 10:35

Hallo André,

ich hoffe, dass die in 2.0.11 enthaltene TSEConnector-Version die finale ist (ok, vielleicht noch ein wenig das TSElog aufhübschen, z.B. mit einer richtigen Seriennummer, wie du in einem anderen Thread hingewiesen hast).

Die Version in der 2.0.11 ist allerdings noch etwas mehr erweitert, als es die Bugfix.Version ist, die ich davor verlinkt hatte.

Ich bin gespannt.

Gruß,

Stefan

Re: BUG: TSE Connector

von misery » Di 27. Okt 2020, 09:18

pichel hat geschrieben: Sa 24. Okt 2020, 16:31 Hier ist die neue Version (entpacken und die jar ersetzen):

http://www.ordersprinter.de/bugfixes/tseconnector.zip

Hilft diese Version nun?

Gruß,

Stefan
Moin Stefan,

ich habe die Version gestern installieren können. Mal schauen, was die so bringt. :-)
Das Problem von oben mit dem SELF_TEST ist auch nur ein einziges Mal aufgetreten. Könnte also länger dauern den Fix zu bestätigen.

Viele Grüße
André

Re: BUG: TSE Connector

von Flo » So 25. Okt 2020, 20:30

Riesen Dank für diese super schnelle Reaktion!

Der 2.0.11 TSE-Connector ist eingespielt und das Problem ist behoben :mrgreen:

Klasse!!

Schönen Abend.
VG
Florian

Re: BUG: TSE Connector

von pichel » So 25. Okt 2020, 14:27

Hallo Florian,

vielen Dank für das Log. Ich habe den TSEConnector nun nochmal angepasst, so dass hoffentlich auch dein Service-Neustart-Problem damit gelöst sein sollte. Die neue Version habe ich in einer neuen 2.0.11 gerade veröffentlicht. Du musst aber nur den TSEConnector aus der Suite nehmen, denn die anderen Änderungen sind für dich nicht relevant, weil sie sich auf den Installer beziehen.

Gruß,

Stefan

Re: BUG: TSE Connector

von Flo » So 25. Okt 2020, 10:41

Hallo Herr Pichel,

anbei die Ausgabe. Wie beschrieben wird dann manuell ein Selbsttest forciert, nach welchem alles klappt.

Code: Alles auswählen

2020-10-25T10:17:52.837467: Start TSEConnector mit diesen Parametern:
2020-10-25T10:17:52.844206: pass= 123
2020-10-25T10:17:52.844675: port= 8000
2020-10-25T10:17:52.845052: supplier= swissbit
2020-10-25T10:17:52.845400: mountpoint=/media/kasse/SWISSBIT
2020-10-25T10:17:52.845758: mode= normal
2020-10-25T10:17:52.846195: loglevel= 2
2020-10-25T10:17:52.846671: showlog= 1
2020-10-25T10:22:33.099411: Abgeschlossen Startup des TSEConnectors
2020-10-25T10:24:55.076376: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:24:55.448146: isCheckUseful = true: current = 1603617895, lastSucces=0
2020-10-25T10:24:55.448287: Check for TSE Status and availability
2020-10-25T10:25:00.061429: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:00.069112: isCheckUseful = true: current = 1603617900, lastSucces=0
2020-10-25T10:25:00.069219: Check for TSE Status and availability
2020-10-25T10:25:05.061420: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:05.067570: isCheckUseful = true: current = 1603617905, lastSucces=0
2020-10-25T10:25:05.067770: Check for TSE Status and availability
2020-10-25T10:25:10.784014: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:10.790689: isCheckUseful = true: current = 1603617910, lastSucces=0
2020-10-25T10:25:10.790863: Check for TSE Status and availability
2020-10-25T10:25:15.782713: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:15.788168: isCheckUseful = true: current = 1603617915, lastSucces=0
2020-10-25T10:25:15.788362: Check for TSE Status and availability
2020-10-25T10:25:20.783796: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:20.788715: isCheckUseful = true: current = 1603617920, lastSucces=0
2020-10-25T10:25:20.788845: Check for TSE Status and availability
2020-10-25T10:25:25.783095: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:25.788473: isCheckUseful = true: current = 1603617925, lastSucces=0
2020-10-25T10:25:25.788727: Check for TSE Status and availability
2020-10-25T10:25:30.781696: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:30.785980: isCheckUseful = true: current = 1603617930, lastSucces=0
2020-10-25T10:25:30.786108: Check for TSE Status and availability
2020-10-25T10:25:35.783505: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:35.788375: isCheckUseful = true: current = 1603617935, lastSucces=0
2020-10-25T10:25:35.788484: Check for TSE Status and availability
2020-10-25T10:25:40.784411: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:40.789532: isCheckUseful = true: current = 1603617940, lastSucces=0
2020-10-25T10:25:40.789676: Check for TSE Status and availability
2020-10-25T10:25:45.785299: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:45.789682: isCheckUseful = true: current = 1603617945, lastSucces=0
2020-10-25T10:25:45.789810: Check for TSE Status and availability
2020-10-25T10:25:47.863557: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"puk":[1,2,3,4,5,6],"clientid":"ORD1","cmd":"getinfo"}
2020-10-25T10:25:50.783205: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:25:50.789528: isCheckUseful = true: current = 1603617950, lastSucces=0
2020-10-25T10:25:50.789696: Check for TSE Status and availability
2020-10-25T10:25:52.264914: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"puk":[1,2,3,4,5,6],"clientid":"ORD1","cmd":"forceselftest"}
2020-10-25T10:25:53.109284: Trying to do self test without admin login and registering client
2020-10-25T10:25:53.109395: Start Self Test
2020-10-25T10:26:06.776274: Completed Self Test
2020-10-25T10:26:07.621928: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:07.628008: isCheckUseful = true: current = 1603617967, lastSucces=0
2020-10-25T10:26:07.628264: Check for TSE Status and availability
2020-10-25T10:26:07.836321: TSE has no valid time. Set time now.
2020-10-25T10:26:07.836543: TSE needs time sync because soon expiration of maxSyncTime after last time sync
2020-10-25T10:26:08.566496: TSE time is set
2020-10-25T10:26:09.805867: TSE time is set
2020-10-25T10:26:09.819771: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:09.832565: isCheckUseful = false: current = 1603617969, lastSucces=1603617969
2020-10-25T10:26:09.832727: Ignore Check request - last check not that far in the past
2020-10-25T10:26:09.839408: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:09.842930: isCheckUseful = false: current = 1603617969, lastSucces=1603617969
2020-10-25T10:26:09.843053: Ignore Check request - last check not that far in the past
2020-10-25T10:26:10.784649: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:10.788782: isCheckUseful = false: current = 1603617970, lastSucces=1603617969
2020-10-25T10:26:10.788883: Ignore Check request - last check not that far in the past
2020-10-25T10:26:15.786024: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:15.789579: isCheckUseful = false: current = 1603617975, lastSucces=1603617969
2020-10-25T10:26:15.789717: Ignore Check request - last check not that far in the past
2020-10-25T10:26:17.842349: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"puk":[1,2,3,4,5,6],"clientid":"ORD1","cmd":"getinfo"}
2020-10-25T10:26:21.345469: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:21.349775: isCheckUseful = false: current = 1603617981, lastSucces=1603617969
2020-10-25T10:26:21.350040: Ignore Check request - last check not that far in the past
2020-10-25T10:26:26.345445: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:26.349862: isCheckUseful = false: current = 1603617986, lastSucces=1603617969
2020-10-25T10:26:26.349977: Ignore Check request - last check not that far in the past
2020-10-25T10:26:31.345139: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:31.349991: isCheckUseful = false: current = 1603617991, lastSucces=1603617969
2020-10-25T10:26:31.350168: Ignore Check request - last check not that far in the past
2020-10-25T10:26:36.344051: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:36.348331: isCheckUseful = false: current = 1603617996, lastSucces=1603617969
2020-10-25T10:26:36.348450: Ignore Check request - last check not that far in the past
2020-10-25T10:26:41.344261: Transferred POST-Data: {"pass":"tsepass","pin":[1,2,3,4,5],"clientid":"ORD1","cmd":"check"}
2020-10-25T10:26:41.348731: isCheckUseful = false: current = 1603618001, lastSucces=1603617969
2020-10-25T10:26:41.348847: Ignore Check request - last check not that far in the past
Vielen Dank für die Hilfe
VG
Florian

Re: BUG: TSE Connector

von pichel » Sa 24. Okt 2020, 22:15

Hallo Florian,

magst du mir die Ausgabe vom TSEConnector nach dem Reboot zuschicken, der zeigt, wie er aus dem Problem mit der Zeitsynchronisation nicht mehr rauskommt?

Gruß,

Stefan

Re: BUG: TSE Connector

von Flo » Sa 24. Okt 2020, 20:53

Hallo Herr Pichel,

ich habe heute eine frische 2.0.10 Installation, aufgesetzt mit dem Ubuntuinstaller Skript, mit dem Bugfix ausgestattet und eine Ersteinrichtung der TSE nach Anleitung durchgeführt. Soweit so gut - alles läuft.

Jedoch ist das Ganze leider weiterhin nicht rebootfest. Nach dem Neustart des Gesamtsystems ergibt die TSE-Info: hasvalidtime = false.

Wenn ich dann einen Selbsttest starte wird dieser erfolgreich durchlaufen und danach ist die TSE scheinbar einsatzbereit (hasvalidtime =true)

Haben Sie hierzu eine Idee / kann ich hier irgendwie etwas zur Lösung beitragen?

Herzlichen Dank für Ihre Arbeit.

Mfg
Florian

Re: BUG: TSE Connector

von pichel » Sa 24. Okt 2020, 16:31

Hallo,

ich habe eine verbesserte Version des TSEConnectors gebaut, diese könnte den WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST verhindern. Bisher hatte ich vor jedem Self Test ein Admin Login durchgeführt, um einen registerClient vornehmen zu können, denn ein Client muss vor jedem Self Test registriert sein und da gab es in der Vergangenheit mal einen berichteten Fehler. Das Admin-Login ist offenbar jedoch nicht in jedem Status erlaubt, so dass ich im Fehlerfall nun einen Self Test durchführe und darauf vertraue, dass der Client bereits registriert ist (das sollte eigentlich immer nach dem Setup bereits der Fall sein).

Hier ist die neue Version (entpacken und die jar ersetzen):

http://www.ordersprinter.de/bugfixes/tseconnector.zip

Hilft diese Version nun?

Gruß,

Stefan

Re: BUG: TSE Connector

von pichel » Fr 23. Okt 2020, 21:30

Hallo André,

im Zuge der regelmäßigen Checks sollte der Fehler abgefangen sein, aber noch nicht bei fehlgeschlagenen Signierungen. Wobei ich mich frage, ob der Fehler nicht erst dann in diesem Fall auftreten sollte, wenn die TSE sowieso bereits einen Defekt hat, denn sonst sollte das regelmäßige Checken den Self Test ausreichend häufig aufrufen.

Aber ja, der Abfangen dieser Exception bei einer Signierung werde ich auch noch einbauen.

Gruß,

Stefan

Re: BUG: TSE Connector

von misery » Do 22. Okt 2020, 18:48

Bisher ist es nun einmal seit dem Update auf die 2.0.8 zu einem TSE-Problem gekommen.

Hier das vollständige TSE-Log von dem Tag.

Code: Alles auswählen

08:00:00 systemd[1]: Stopping TSE...
08:00:00 systemd[1]: tse.service: Succeeded.
08:00:00 systemd[1]: Stopped TSE.
08:00:00 systemd[1]: Started TSE.
08:00:01 : TSEConnectors kann folgende Parameter verarbeiten:
08:00:01 :    pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
08:00:01 :    port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
08:00:01 :    mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
08:00:01 :    mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
08:00:01 :    loglevel=number  0: nur relevante Infos, 1: Signierungen, 2: umfangreiches Logging.
08:00:01 :    showlog=number  0: kein Zugriff auf Log via Web, 1: Zugriff erlauben ohne Passwortabfrage (default: 1)
08:00:01 : Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
08:00:01 :    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
08:00:01 : 08:00:01.534: Start TSEConnector mit diesen Parametern:
08:00:01 : 08:00:01.570:   pass=      XXX
08:00:01 : 08:00:01.570:   port=      6666
08:00:01 : 08:00:01.570:   supplier=  swissbit
08:00:01 : 08:00:01.571:   mountpoint=/mnt/tse
08:00:01 : 08:00:01.571:   mode=      normal
08:00:01 : 08:00:01.571:   loglevel=  2
08:00:01 : 08:00:01.571:   showlog=   1
08:00:01 : TSE seems to be mounted
08:00:01 : Sync Time = 1800 seconds (30 minutes)
08:00:01 : 08:00:01.642: Abgeschlossen Startup des TSEConnectors
13:55:54 : 13:55:54.905: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:55:55 : 13:55:55.775: isCheckUseful = true: current = 1603194955, lastSucces=0
13:55:55 : 13:55:55.776: Check for TSE Status and availability
13:55:55 : 13:55:55.785: TSE has no valid time. Set time now.
13:55:55 : 13:55:55.786: TSE needs time sync because soon expiration of maxSyncTime after last time sync
13:55:56 : 13:55:56.147: TSE time is set
13:55:56 : 13:55:56.552: TSE time is set
13:55:59 : 13:55:59.901: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:55:59 : 13:55:59.914: isCheckUseful = false: current = 1603194959, lastSucces=1603194956
13:55:59 : 13:55:59.914: Ignore Check request - last check not that far in the past
13:56:04 : 13:56:04.907: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:04 : 13:56:04.914: isCheckUseful = false: current = 1603194964, lastSucces=1603194956
13:56:04 : 13:56:04.914: Ignore Check request - last check not that far in the past
13:56:09 : 13:56:09.900: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:09 : 13:56:09.911: isCheckUseful = false: current = 1603194969, lastSucces=1603194956
13:56:09 : 13:56:09.912: Ignore Check request - last check not that far in the past
13:56:14 : 13:56:14.905: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:14 : 13:56:14.917: isCheckUseful = false: current = 1603194974, lastSucces=1603194956
13:56:14 : 13:56:14.918: Ignore Check request - last check not that far in the past
13:56:19 : 13:56:19.900: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:19 : 13:56:19.911: isCheckUseful = false: current = 1603194979, lastSucces=1603194956
13:56:19 : 13:56:19.911: Ignore Check request - last check not that far in the past
13:56:24 : 13:56:24.906: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:24 : 13:56:24.917: isCheckUseful = false: current = 1603194984, lastSucces=1603194956
13:56:24 : 13:56:24.917: Ignore Check request - last check not that far in the past
13:56:29 : 13:56:29.902: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:29 : 13:56:29.909: isCheckUseful = false: current = 1603194989, lastSucces=1603194956
13:56:29 : 13:56:29.910: Ignore Check request - last check not that far in the past
13:56:34 : 13:56:34.906: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:34 : 13:56:34.916: isCheckUseful = false: current = 1603194994, lastSucces=1603194956
13:56:34 : 13:56:34.916: Ignore Check request - last check not that far in the past
13:56:39 : 13:56:39.902: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:39 : 13:56:39.909: isCheckUseful = false: current = 1603194999, lastSucces=1603194956
13:56:39 : 13:56:39.909: Ignore Check request - last check not that far in the past
13:56:44 : 13:56:44.906: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:44 : 13:56:44.921: isCheckUseful = false: current = 1603195004, lastSucces=1603194956
13:56:44 : 13:56:44.921: Ignore Check request - last check not that far in the past
13:56:49 : 13:56:49.948: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:49 : 13:56:49.963: isCheckUseful = false: current = 1603195009, lastSucces=1603194956
13:56:49 : 13:56:49.964: Ignore Check request - last check not that far in the past
13:56:54 : 13:56:54.907: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:54 : 13:56:54.918: isCheckUseful = false: current = 1603195014, lastSucces=1603194956
13:56:54 : 13:56:54.919: Ignore Check request - last check not that far in the past
13:56:59 : 13:56:59.903: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:56:59 : 13:56:59.911: isCheckUseful = false: current = 1603195019, lastSucces=1603194956
13:56:59 : 13:56:59.912: Ignore Check request - last check not that far in the past
13:57:04 : 13:57:04.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:04 : 13:57:04.920: isCheckUseful = false: current = 1603195024, lastSucces=1603194956
13:57:04 : 13:57:04.921: Ignore Check request - last check not that far in the past
13:57:09 : 13:57:09.904: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:09 : 13:57:09.920: isCheckUseful = false: current = 1603195029, lastSucces=1603194956
13:57:09 : 13:57:09.921: Ignore Check request - last check not that far in the past
13:57:14 : 13:57:14.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:14 : 13:57:14.916: isCheckUseful = false: current = 1603195034, lastSucces=1603194956
13:57:14 : 13:57:14.916: Ignore Check request - last check not that far in the past
13:57:19 : 13:57:19.905: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:19 : 13:57:19.915: isCheckUseful = false: current = 1603195039, lastSucces=1603194956
13:57:19 : 13:57:19.916: Ignore Check request - last check not that far in the past
13:57:24 : 13:57:24.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:24 : 13:57:24.919: isCheckUseful = false: current = 1603195044, lastSucces=1603194956
13:57:24 : 13:57:24.920: Ignore Check request - last check not that far in the past
13:57:29 : 13:57:29.951: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:29 : 13:57:29.959: isCheckUseful = false: current = 1603195049, lastSucces=1603194956
13:57:29 : 13:57:29.959: Ignore Check request - last check not that far in the past
13:57:34 : 13:57:34.910: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:34 : 13:57:34.917: isCheckUseful = false: current = 1603195054, lastSucces=1603194956
13:57:34 : 13:57:34.918: Ignore Check request - last check not that far in the past
13:57:39 : 13:57:39.906: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:39 : 13:57:39.913: isCheckUseful = false: current = 1603195059, lastSucces=1603194956
13:57:39 : 13:57:39.914: Ignore Check request - last check not that far in the past
13:57:44 : 13:57:44.910: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:44 : 13:57:44.923: isCheckUseful = false: current = 1603195064, lastSucces=1603194956
13:57:44 : 13:57:44.923: Ignore Check request - last check not that far in the past
13:57:49 : 13:57:49.906: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:49 : 13:57:49.915: isCheckUseful = false: current = 1603195069, lastSucces=1603194956
13:57:49 : 13:57:49.915: Ignore Check request - last check not that far in the past
13:57:54 : 13:57:54.910: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:54 : 13:57:54.918: isCheckUseful = false: current = 1603195074, lastSucces=1603194956
13:57:54 : 13:57:54.918: Ignore Check request - last check not that far in the past
13:57:59 : 13:57:59.907: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:57:59 : 13:57:59.915: isCheckUseful = false: current = 1603195079, lastSucces=1603194956
13:57:59 : 13:57:59.915: Ignore Check request - last check not that far in the past
13:58:04 : 13:58:04.911: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:04 : 13:58:04.917: isCheckUseful = false: current = 1603195084, lastSucces=1603194956
13:58:04 : 13:58:04.918: Ignore Check request - last check not that far in the past
13:58:09 : 13:58:09.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:09 : 13:58:09.914: isCheckUseful = false: current = 1603195089, lastSucces=1603194956
13:58:09 : 13:58:09.915: Ignore Check request - last check not that far in the past
13:58:14 : 13:58:14.912: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:14 : 13:58:14.921: isCheckUseful = false: current = 1603195094, lastSucces=1603194956
13:58:14 : 13:58:14.922: Ignore Check request - last check not that far in the past
13:58:19 : 13:58:19.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:19 : 13:58:19.917: isCheckUseful = false: current = 1603195099, lastSucces=1603194956
13:58:19 : 13:58:19.917: Ignore Check request - last check not that far in the past
13:58:24 : 13:58:24.912: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:24 : 13:58:24.918: isCheckUseful = false: current = 1603195104, lastSucces=1603194956
13:58:24 : 13:58:24.918: Ignore Check request - last check not that far in the past
13:58:29 : 13:58:29.908: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:29 : 13:58:29.915: isCheckUseful = false: current = 1603195109, lastSucces=1603194956
13:58:29 : 13:58:29.916: Ignore Check request - last check not that far in the past
13:58:34 : 13:58:34.916: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:34 : 13:58:34.923: isCheckUseful = false: current = 1603195114, lastSucces=1603194956
13:58:34 : 13:58:34.924: Ignore Check request - last check not that far in the past
13:58:39 : 13:58:39.909: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:39 : 13:58:39.915: isCheckUseful = false: current = 1603195119, lastSucces=1603194956
13:58:39 : 13:58:39.915: Ignore Check request - last check not that far in the past
13:58:44 : 13:58:44.914: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:44 : 13:58:44.936: isCheckUseful = false: current = 1603195124, lastSucces=1603194956
13:58:44 : 13:58:44.939: Ignore Check request - last check not that far in the past
13:58:49 : 13:58:49.910: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:49 : 13:58:49.915: isCheckUseful = false: current = 1603195129, lastSucces=1603194956
13:58:49 : 13:58:49.915: Ignore Check request - last check not that far in the past
13:58:54 : 13:58:54.915: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:54 : 13:58:54.922: isCheckUseful = false: current = 1603195134, lastSucces=1603194956
13:58:54 : 13:58:54.923: Ignore Check request - last check not that far in the past
13:58:59 : 13:58:59.910: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:58:59 : 13:58:59.915: isCheckUseful = false: current = 1603195139, lastSucces=1603194956
13:58:59 : 13:58:59.915: Ignore Check request - last check not that far in the past
13:59:04 : 13:59:04.915: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:04 : 13:59:04.921: isCheckUseful = false: current = 1603195144, lastSucces=1603194956
13:59:04 : 13:59:04.921: Ignore Check request - last check not that far in the past
13:59:09 : 13:59:09.912: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:09 : 13:59:09.916: isCheckUseful = false: current = 1603195149, lastSucces=1603194956
13:59:09 : 13:59:09.917: Ignore Check request - last check not that far in the past
13:59:14 : 13:59:14.916: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:14 : 13:59:14.920: isCheckUseful = false: current = 1603195154, lastSucces=1603194956
13:59:14 : 13:59:14.921: Ignore Check request - last check not that far in the past
13:59:19 : 13:59:19.912: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:19 : 13:59:19.917: isCheckUseful = false: current = 1603195159, lastSucces=1603194956
13:59:19 : 13:59:19.917: Ignore Check request - last check not that far in the past
13:59:24 : 13:59:24.916: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:24 : 13:59:24.921: isCheckUseful = false: current = 1603195164, lastSucces=1603194956
13:59:24 : 13:59:24.922: Ignore Check request - last check not that far in the past
13:59:29 : 13:59:29.914: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:29 : 13:59:29.918: isCheckUseful = false: current = 1603195169, lastSucces=1603194956
13:59:29 : 13:59:29.918: Ignore Check request - last check not that far in the past
13:59:34 : 13:59:34.949: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:34 : 13:59:34.953: isCheckUseful = false: current = 1603195174, lastSucces=1603194956
13:59:34 : 13:59:34.954: Ignore Check request - last check not that far in the past
13:59:39 : 13:59:39.912: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:39 : 13:59:39.922: isCheckUseful = false: current = 1603195179, lastSucces=1603194956
13:59:39 : 13:59:39.922: Ignore Check request - last check not that far in the past
13:59:44 : 13:59:44.917: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:44 : 13:59:44.922: isCheckUseful = false: current = 1603195184, lastSucces=1603194956
13:59:44 : 13:59:44.922: Ignore Check request - last check not that far in the past
13:59:49 : 13:59:49.914: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:49 : 13:59:49.922: isCheckUseful = false: current = 1603195189, lastSucces=1603194956
13:59:49 : 13:59:49.923: Ignore Check request - last check not that far in the past
13:59:54 : 13:59:54.918: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:54 : 13:59:54.923: isCheckUseful = false: current = 1603195194, lastSucces=1603194956
13:59:54 : 13:59:54.924: Ignore Check request - last check not that far in the past
13:59:59 : 13:59:59.915: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
13:59:59 : 13:59:59.920: isCheckUseful = false: current = 1603195199, lastSucces=1603194956
13:59:59 : 13:59:59.920: Ignore Check request - last check not that far in the past
14:00:04 : 14:00:04.918: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:04 : 14:00:04.923: isCheckUseful = false: current = 1603195204, lastSucces=1603194956
14:00:04 : 14:00:04.923: Ignore Check request - last check not that far in the past
14:00:09 : 14:00:09.915: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:09 : 14:00:09.919: isCheckUseful = false: current = 1603195209, lastSucces=1603194956
14:00:09 : 14:00:09.919: Ignore Check request - last check not that far in the past
14:00:14 : 14:00:14.915: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:14 : 14:00:14.919: isCheckUseful = false: current = 1603195214, lastSucces=1603194956
14:00:14 : 14:00:14.920: Ignore Check request - last check not that far in the past
14:00:20 : 14:00:20.251: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:20 : 14:00:20.257: isCheckUseful = false: current = 1603195220, lastSucces=1603194956
14:00:20 : 14:00:20.257: Ignore Check request - last check not that far in the past
14:00:25 : 14:00:25.260: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:25 : 14:00:25.265: isCheckUseful = false: current = 1603195225, lastSucces=1603194956
14:00:25 : 14:00:25.265: Ignore Check request - last check not that far in the past
14:00:30 : 14:00:30.258: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:30 : 14:00:30.261: isCheckUseful = false: current = 1603195230, lastSucces=1603194956
14:00:30 : 14:00:30.262: Ignore Check request - last check not that far in the past
14:00:31 : 14:00:31.105: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:31 : 14:00:31.108: isCheckUseful = false: current = 1603195231, lastSucces=1603194956
14:00:31 : 14:00:31.109: Ignore Check request - last check not that far in the past
14:00:36 : 14:00:36.104: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:36 : 14:00:36.110: isCheckUseful = false: current = 1603195236, lastSucces=1603194956
14:00:36 : 14:00:36.110: Ignore Check request - last check not that far in the past
14:00:41 : 14:00:41.150: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:41 : 14:00:41.157: isCheckUseful = false: current = 1603195241, lastSucces=1603194956
14:00:41 : 14:00:41.158: Ignore Check request - last check not that far in the past
14:00:46 : 14:00:46.104: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:46 : 14:00:46.108: isCheckUseful = false: current = 1603195246, lastSucces=1603194956
14:00:46 : 14:00:46.108: Ignore Check request - last check not that far in the past
14:00:51 : 14:00:51.108: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:51 : 14:00:51.113: isCheckUseful = false: current = 1603195251, lastSucces=1603194956
14:00:51 : 14:00:51.114: Ignore Check request - last check not that far in the past
14:00:56 : 14:00:56.105: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:00:56 : 14:00:56.112: isCheckUseful = false: current = 1603195256, lastSucces=1603194956
14:00:56 : 14:00:56.112: Ignore Check request - last check not that far in the past
14:01:01 : 14:01:01.110: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:01 : 14:01:01.114: isCheckUseful = false: current = 1603195261, lastSucces=1603194956
14:01:01 : 14:01:01.115: Ignore Check request - last check not that far in the past
14:01:06 : 14:01:06.171: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:06 : 14:01:06.176: isCheckUseful = false: current = 1603195266, lastSucces=1603194956
14:01:06 : 14:01:06.176: Ignore Check request - last check not that far in the past
14:01:11 : 14:01:11.110: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:11 : 14:01:11.114: isCheckUseful = false: current = 1603195271, lastSucces=1603194956
14:01:11 : 14:01:11.114: Ignore Check request - last check not that far in the past
14:01:16 : 14:01:16.104: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:16 : 14:01:16.108: isCheckUseful = false: current = 1603195276, lastSucces=1603194956
14:01:16 : 14:01:16.108: Ignore Check request - last check not that far in the past
14:01:21 : 14:01:21.110: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:21 : 14:01:21.115: isCheckUseful = false: current = 1603195281, lastSucces=1603194956
14:01:21 : 14:01:21.115: Ignore Check request - last check not that far in the past
14:01:26 : 14:01:26.145: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:26 : 14:01:26.149: isCheckUseful = false: current = 1603195286, lastSucces=1603194956
14:01:26 : 14:01:26.149: Ignore Check request - last check not that far in the past
14:01:26 : 14:01:26.364: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:01:26 : 14:01:26.370: Starte Signierung
14:01:26 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:01:26 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:01:26 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:01:26 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:01:26 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:01:26 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:01:26 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:01:26 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:26 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:01:26 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:01:26 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:01:26 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:26 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:01:26 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:01:26 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:01:26 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:01:26 :         at java.lang.Thread.run(Thread.java:748)
14:01:30 : 14:01:30.639: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:01:30 : 14:01:30.644: Starte Signierung
14:01:30 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:01:30 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:01:30 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:01:30 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:01:30 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:01:30 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:01:30 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:01:30 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:30 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:01:30 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:01:30 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:01:30 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:30 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:01:30 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:01:30 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:01:30 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:01:30 :         at java.lang.Thread.run(Thread.java:748)
14:01:31 : 14:01:31.580: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:31 : 14:01:31.585: isCheckUseful = false: current = 1603195291, lastSucces=1603194956
14:01:31 : 14:01:31.585: Ignore Check request - last check not that far in the past
14:01:33 : 14:01:33.287: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:01:33 : 14:01:33.293: Starte Signierung
14:01:33 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:01:33 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:01:33 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:01:33 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:01:33 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:01:33 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:01:33 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:01:33 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:33 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:01:33 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:01:33 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:01:33 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:01:33 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:01:33 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:01:33 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:01:33 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:01:33 :         at java.lang.Thread.run(Thread.java:748)
14:01:36 : 14:01:36.115: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:36 : 14:01:36.121: isCheckUseful = false: current = 1603195296, lastSucces=1603194956
14:01:36 : 14:01:36.121: Ignore Check request - last check not that far in the past
14:01:41 : 14:01:41.111: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:41 : 14:01:41.116: isCheckUseful = false: current = 1603195301, lastSucces=1603194956
14:01:41 : 14:01:41.116: Ignore Check request - last check not that far in the past
14:01:46 : 14:01:46.108: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:46 : 14:01:46.112: isCheckUseful = false: current = 1603195306, lastSucces=1603194956
14:01:46 : 14:01:46.113: Ignore Check request - last check not that far in the past
14:01:51 : 14:01:51.111: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:51 : 14:01:51.116: isCheckUseful = false: current = 1603195311, lastSucces=1603194956
14:01:51 : 14:01:51.116: Ignore Check request - last check not that far in the past
14:01:56 : 14:01:56.108: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:01:56 : 14:01:56.113: isCheckUseful = false: current = 1603195316, lastSucces=1603194956
14:01:56 : 14:01:56.113: Ignore Check request - last check not that far in the past
14:02:01 : 14:02:01.113: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:01 : 14:02:01.118: isCheckUseful = false: current = 1603195321, lastSucces=1603194956
14:02:01 : 14:02:01.118: Ignore Check request - last check not that far in the past
14:02:06 : 14:02:06.109: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:06 : 14:02:06.113: isCheckUseful = false: current = 1603195326, lastSucces=1603194956
14:02:06 : 14:02:06.113: Ignore Check request - last check not that far in the past
14:02:11 : 14:02:11.113: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:11 : 14:02:11.116: isCheckUseful = false: current = 1603195331, lastSucces=1603194956
14:02:11 : 14:02:11.117: Ignore Check request - last check not that far in the past
14:02:16 : 14:02:16.109: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:16 : 14:02:16.114: isCheckUseful = false: current = 1603195336, lastSucces=1603194956
14:02:16 : 14:02:16.114: Ignore Check request - last check not that far in the past
14:02:21 : 14:02:21.117: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:21 : 14:02:21.122: isCheckUseful = false: current = 1603195341, lastSucces=1603194956
14:02:21 : 14:02:21.122: Ignore Check request - last check not that far in the past
14:02:26 : 14:02:26.110: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:26 : 14:02:26.118: isCheckUseful = false: current = 1603195346, lastSucces=1603194956
14:02:26 : 14:02:26.118: Ignore Check request - last check not that far in the past
14:02:31 : 14:02:31.138: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:31 : 14:02:31.141: isCheckUseful = false: current = 1603195351, lastSucces=1603194956
14:02:31 : 14:02:31.142: Ignore Check request - last check not that far in the past
14:02:36 : 14:02:36.111: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:36 : 14:02:36.115: isCheckUseful = false: current = 1603195356, lastSucces=1603194956
14:02:36 : 14:02:36.115: Ignore Check request - last check not that far in the past
14:02:41 : 14:02:41.115: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:41 : 14:02:41.118: isCheckUseful = false: current = 1603195361, lastSucces=1603194956
14:02:41 : 14:02:41.119: Ignore Check request - last check not that far in the past
14:02:46 : 14:02:46.111: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:46 : 14:02:46.116: isCheckUseful = false: current = 1603195366, lastSucces=1603194956
14:02:46 : 14:02:46.116: Ignore Check request - last check not that far in the past
14:02:51 : 14:02:51.116: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:51 : 14:02:51.120: isCheckUseful = false: current = 1603195371, lastSucces=1603194956
14:02:51 : 14:02:51.120: Ignore Check request - last check not that far in the past
14:02:56 : 14:02:56.112: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:02:56 : 14:02:56.116: isCheckUseful = false: current = 1603195376, lastSucces=1603194956
14:02:56 : 14:02:56.116: Ignore Check request - last check not that far in the past
14:03:01 : 14:03:01.116: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:01 : 14:03:01.120: isCheckUseful = false: current = 1603195381, lastSucces=1603194956
14:03:01 : 14:03:01.121: Ignore Check request - last check not that far in the past
14:03:06 : 14:03:06.113: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:06 : 14:03:06.117: isCheckUseful = false: current = 1603195386, lastSucces=1603194956
14:03:06 : 14:03:06.117: Ignore Check request - last check not that far in the past
14:03:11 : 14:03:11.116: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:11 : 14:03:11.122: isCheckUseful = false: current = 1603195391, lastSucces=1603194956
14:03:11 : 14:03:11.122: Ignore Check request - last check not that far in the past
14:03:16 : 14:03:16.113: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:16 : 14:03:16.117: isCheckUseful = false: current = 1603195396, lastSucces=1603194956
14:03:16 : 14:03:16.117: Ignore Check request - last check not that far in the past
14:03:21 : 14:03:21.118: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:21 : 14:03:21.122: isCheckUseful = false: current = 1603195401, lastSucces=1603194956
14:03:21 : 14:03:21.122: Ignore Check request - last check not that far in the past
14:03:26 : 14:03:26.114: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:26 : 14:03:26.120: isCheckUseful = false: current = 1603195406, lastSucces=1603194956
14:03:26 : 14:03:26.120: Ignore Check request - last check not that far in the past
14:03:31 : 14:03:31.153: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:31 : 14:03:31.158: isCheckUseful = false: current = 1603195411, lastSucces=1603194956
14:03:31 : 14:03:31.158: Ignore Check request - last check not that far in the past
14:03:36 : 14:03:36.115: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:36 : 14:03:36.121: isCheckUseful = false: current = 1603195416, lastSucces=1603194956
14:03:36 : 14:03:36.122: Ignore Check request - last check not that far in the past
14:03:41 : 14:03:41.119: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:41 : 14:03:41.125: isCheckUseful = false: current = 1603195421, lastSucces=1603194956
14:03:41 : 14:03:41.125: Ignore Check request - last check not that far in the past
14:03:46 : 14:03:46.117: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:46 : 14:03:46.121: isCheckUseful = false: current = 1603195426, lastSucces=1603194956
14:03:46 : 14:03:46.121: Ignore Check request - last check not that far in the past
14:03:51 : 14:03:51.119: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:51 : 14:03:51.123: isCheckUseful = false: current = 1603195431, lastSucces=1603194956
14:03:51 : 14:03:51.123: Ignore Check request - last check not that far in the past
14:03:56 : 14:03:56.135: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:03:56 : 14:03:56.139: isCheckUseful = false: current = 1603195436, lastSucces=1603194956
14:03:56 : 14:03:56.139: Ignore Check request - last check not that far in the past
14:04:01 : 14:04:01.120: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:01 : 14:04:01.123: isCheckUseful = false: current = 1603195441, lastSucces=1603194956
14:04:01 : 14:04:01.123: Ignore Check request - last check not that far in the past
14:04:06 : 14:04:06.117: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:06 : 14:04:06.121: isCheckUseful = false: current = 1603195446, lastSucces=1603194956
14:04:06 : 14:04:06.122: Ignore Check request - last check not that far in the past
14:04:11 : 14:04:11.121: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:11 : 14:04:11.124: isCheckUseful = false: current = 1603195451, lastSucces=1603194956
14:04:11 : 14:04:11.124: Ignore Check request - last check not that far in the past
14:04:16 : 14:04:16.118: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:16 : 14:04:16.121: isCheckUseful = false: current = 1603195456, lastSucces=1603194956
14:04:16 : 14:04:16.121: Ignore Check request - last check not that far in the past
14:04:21 : 14:04:21.121: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:21 : 14:04:21.124: isCheckUseful = false: current = 1603195461, lastSucces=1603194956
14:04:21 : 14:04:21.124: Ignore Check request - last check not that far in the past
14:04:26 : 14:04:26.118: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:26 : 14:04:26.122: isCheckUseful = false: current = 1603195466, lastSucces=1603194956
14:04:26 : 14:04:26.122: Ignore Check request - last check not that far in the past
14:04:31 : 14:04:31.145: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:31 : 14:04:31.149: isCheckUseful = false: current = 1603195471, lastSucces=1603194956
14:04:31 : 14:04:31.149: Ignore Check request - last check not that far in the past
14:04:36 : 14:04:36.118: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:36 : 14:04:36.123: isCheckUseful = false: current = 1603195476, lastSucces=1603194956
14:04:36 : 14:04:36.123: Ignore Check request - last check not that far in the past
14:04:41 : 14:04:41.124: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:41 : 14:04:41.127: isCheckUseful = false: current = 1603195481, lastSucces=1603194956
14:04:41 : 14:04:41.128: Ignore Check request - last check not that far in the past
14:04:46 : 14:04:46.120: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:46 : 14:04:46.124: isCheckUseful = false: current = 1603195486, lastSucces=1603194956
14:04:46 : 14:04:46.124: Ignore Check request - last check not that far in the past
14:04:51 : 14:04:51.123: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:51 : 14:04:51.127: isCheckUseful = false: current = 1603195491, lastSucces=1603194956
14:04:51 : 14:04:51.127: Ignore Check request - last check not that far in the past
14:04:56 : 14:04:56.120: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:04:56 : 14:04:56.124: isCheckUseful = false: current = 1603195496, lastSucces=1603194956
14:04:56 : 14:04:56.124: Ignore Check request - last check not that far in the past
14:05:01 : 14:05:01.123: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:01 : 14:05:01.127: isCheckUseful = false: current = 1603195501, lastSucces=1603194956
14:05:01 : 14:05:01.127: Ignore Check request - last check not that far in the past
14:05:06 : 14:05:06.120: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:06 : 14:05:06.123: isCheckUseful = false: current = 1603195506, lastSucces=1603194956
14:05:06 : 14:05:06.124: Ignore Check request - last check not that far in the past
14:05:11 : 14:05:11.125: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:11 : 14:05:11.129: isCheckUseful = false: current = 1603195511, lastSucces=1603194956
14:05:11 : 14:05:11.129: Ignore Check request - last check not that far in the past
14:05:16 : 14:05:16.122: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:16 : 14:05:16.124: isCheckUseful = false: current = 1603195516, lastSucces=1603194956
14:05:16 : 14:05:16.124: Ignore Check request - last check not that far in the past
14:05:21 : 14:05:21.125: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:21 : 14:05:21.128: isCheckUseful = false: current = 1603195521, lastSucces=1603194956
14:05:21 : 14:05:21.128: Ignore Check request - last check not that far in the past
14:05:26 : 14:05:26.122: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:26 : 14:05:26.127: isCheckUseful = false: current = 1603195526, lastSucces=1603194956
14:05:26 : 14:05:26.127: Ignore Check request - last check not that far in the past
14:05:31 : 14:05:31.152: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:31 : 14:05:31.155: isCheckUseful = false: current = 1603195531, lastSucces=1603194956
14:05:31 : 14:05:31.155: Ignore Check request - last check not that far in the past
14:05:36 : 14:05:36.124: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:36 : 14:05:36.127: isCheckUseful = false: current = 1603195536, lastSucces=1603194956
14:05:36 : 14:05:36.127: Ignore Check request - last check not that far in the past
14:05:41 : 14:05:41.127: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:41 : 14:05:41.130: isCheckUseful = false: current = 1603195541, lastSucces=1603194956
14:05:41 : 14:05:41.130: Ignore Check request - last check not that far in the past
14:05:46 : 14:05:46.123: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:46 : 14:05:46.127: isCheckUseful = false: current = 1603195546, lastSucces=1603194956
14:05:46 : 14:05:46.127: Ignore Check request - last check not that far in the past
14:05:51 : 14:05:51.127: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:51 : 14:05:51.130: isCheckUseful = false: current = 1603195551, lastSucces=1603194956
14:05:51 : 14:05:51.131: Ignore Check request - last check not that far in the past
14:05:55 : 14:05:55.011: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:05:55 : 14:05:55.014: Starte Signierung
14:05:55 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:05:55 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:05:55 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:05:55 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:05:55 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:05:55 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:05:55 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:05:55 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:05:55 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:05:55 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:05:55 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:05:55 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:05:55 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:05:55 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:05:55 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:05:55 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:05:55 :         at java.lang.Thread.run(Thread.java:748)
14:05:58 : 14:05:58.146: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:05:58 : 14:05:58.150: isCheckUseful = false: current = 1603195558, lastSucces=1603194956
14:05:58 : 14:05:58.150: Ignore Check request - last check not that far in the past
14:06:01 : 14:06:01.234: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:01 : 14:06:01.240: isCheckUseful = false: current = 1603195561, lastSucces=1603194956
14:06:01 : 14:06:01.241: Ignore Check request - last check not that far in the past
14:06:06 : 14:06:06.139: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:06 : 14:06:06.149: isCheckUseful = false: current = 1603195566, lastSucces=1603194956
14:06:06 : 14:06:06.149: Ignore Check request - last check not that far in the past
14:06:11 : 14:06:11.126: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:11 : 14:06:11.130: isCheckUseful = false: current = 1603195571, lastSucces=1603194956
14:06:11 : 14:06:11.130: Ignore Check request - last check not that far in the past
14:06:15 : 14:06:15.331: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:15 : 14:06:15.335: isCheckUseful = false: current = 1603195575, lastSucces=1603194956
14:06:15 : 14:06:15.335: Ignore Check request - last check not that far in the past
14:06:20 : 14:06:20.345: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:20 : 14:06:20.348: isCheckUseful = false: current = 1603195580, lastSucces=1603194956
14:06:20 : 14:06:20.348: Ignore Check request - last check not that far in the past
14:06:25 : 14:06:25.348: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:25 : 14:06:25.351: isCheckUseful = false: current = 1603195585, lastSucces=1603194956
14:06:25 : 14:06:25.351: Ignore Check request - last check not that far in the past
14:06:30 : 14:06:30.345: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:30 : 14:06:30.348: isCheckUseful = false: current = 1603195590, lastSucces=1603194956
14:06:30 : 14:06:30.348: Ignore Check request - last check not that far in the past
14:06:35 : 14:06:35.333: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:35 : 14:06:35.336: isCheckUseful = false: current = 1603195595, lastSucces=1603194956
14:06:35 : 14:06:35.336: Ignore Check request - last check not that far in the past
14:06:40 : 14:06:40.344: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:40 : 14:06:40.347: isCheckUseful = false: current = 1603195600, lastSucces=1603194956
14:06:40 : 14:06:40.348: Ignore Check request - last check not that far in the past
14:06:45 : 14:06:45.350: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:45 : 14:06:45.353: isCheckUseful = false: current = 1603195605, lastSucces=1603194956
14:06:45 : 14:06:45.353: Ignore Check request - last check not that far in the past
14:06:50 : 14:06:50.345: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:50 : 14:06:50.348: isCheckUseful = false: current = 1603195610, lastSucces=1603194956
14:06:50 : 14:06:50.348: Ignore Check request - last check not that far in the past
14:06:55 : 14:06:55.349: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:06:55 : 14:06:55.352: isCheckUseful = false: current = 1603195615, lastSucces=1603194956
14:06:55 : 14:06:55.352: Ignore Check request - last check not that far in the past
14:07:00 : 14:07:00.334: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:07:00 : 14:07:00.337: Starte Signierung
14:07:00 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:07:00 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:07:00 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:07:00 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:07:00 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:07:00 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:07:00 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:07:00 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:07:00 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:07:00 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:07:00 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:07:00 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:07:00 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:07:00 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:07:00 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:07:00 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:07:00 :         at java.lang.Thread.run(Thread.java:748)
14:07:04 : 14:07:04.953: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:07:04 : 14:07:04.956: isCheckUseful = false: current = 1603195624, lastSucces=1603194956
14:07:04 : 14:07:04.956: Ignore Check request - last check not that far in the past
14:07:05 : 14:07:05.350: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:07:05 : 14:07:05.353: isCheckUseful = false: current = 1603195625, lastSucces=1603194956
14:07:05 : 14:07:05.354: Ignore Check request - last check not that far in the past
14:07:10 : 14:07:10.403: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:07:10 : 14:07:10.407: isCheckUseful = false: current = 1603195630, lastSucces=1603194956
14:07:10 : 14:07:10.407: Ignore Check request - last check not that far in the past
14:07:15 : 14:07:15.338: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:07:15 : 14:07:15.341: isCheckUseful = false: current = 1603195635, lastSucces=1603194956
14:07:15 : 14:07:15.341: Ignore Check request - last check not that far in the past
14:07:18 : 14:07:18.388: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:07:18 : 14:07:18.392: Starte Signierung
14:07:18 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:07:18 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:07:18 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:07:18 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:07:18 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:07:18 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:07:18 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:07:18 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:07:18 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:07:18 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:07:18 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:07:18 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:07:18 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:07:18 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:07:18 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:07:18 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:07:18 :         at java.lang.Thread.run(Thread.java:748)
14:14:51 : 14:14:51.966: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:14:51 : 14:14:51.969: isCheckUseful = false: current = 1603196091, lastSucces=1603194956
14:14:51 : 14:14:51.969: Ignore Check request - last check not that far in the past
14:14:53 : 14:14:53.947: Transferred POST-Data: {... "clientid":"ORD1","cmd":"signorders","value":"1;\"Eiskaffee\";3.90\n1;\"Pinocchio\";3.7\n1;\"Gemischtes Eis\";3.6"}
14:14:53 : 14:14:53.951: Starte Signierung
14:14:53 : com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST
14:14:53 :         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_listStartedTransactions__SWIG_0(Native Method)
14:14:53 :         at com.secureflashcard.wormapi.WormStore.transaction_listStartedTransactions(WormStore.java:803)
14:14:53 :         at tseconnector.commands.TseCmdExecutor.completeUnfinishedTransactions(TseCmdExecutor.java:39)
14:14:53 :         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:52)
14:14:53 :         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:24)
14:14:53 :         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
14:14:53 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:14:53 :         at sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:83)
14:14:53 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:82)
14:14:53 :         at sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:675)
14:14:53 :         at com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:79)
14:14:53 :         at sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:647)
14:14:53 :         at sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:158)
14:14:53 :         at sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:431)
14:14:53 :         at sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:396)
14:14:53 :         at java.lang.Thread.run(Thread.java:748)
14:15:45 : 14:15:45.415: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
14:15:45 : 14:15:45.419: isCheckUseful = false: current = 1603196145, lastSucces=1603194956
14:15:45 : 14:15:45.420: Ignore Check request - last check not that far in the past
14:15:50 : 14:15:50.410: Transferred POST-Data: {... "clientid":"ORD1","cmd":"check"}
Ich habe mit Absicht derzeit nicht aktualisiert, da mir die TSE-Änderungen aussehen, dass sie neue Fehler bringen KÖNNTEN.
Ist das WORM_ERROR_WRONG_STATE_NEEDS_SELF_TEST dort schon berücktsichtigt gewesen oder tritt das vermutlich auf 2.0.10 auch noch auf?

André

Nach oben