WORM_ERROR_NO_TIME_SET

In diesem Forum können Fragen zum OrderSprinter gestellt werden.
misery
Beiträge: 36
Registriert: Do 24. Sep 2020, 09:53

WORM_ERROR_NO_TIME_SET

Beitrag von misery »

Moin Stefan,

heute hatte ich seit letztem Jahr zum ersten Mal wieder Probleme mit der TSE, dass sie keine Uhrzeit hatte. Leider konnte sich der TSEConnector nicht mehr von alleine einkriegen und ich musste den Service durchstarten.

Hast du eine Idee, was da passiert ist?

Code: Alles auswählen

Jul 27 18:53:27 order tse.sh[586]: 2021-07-27T18:53:27.494686: Starte Signierung
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054012:   Last Transaction Details
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054127:   Log Time: 1627404810
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054155:   Signature Counter: 15610
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054180:   Transaction Number: 4284
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054213:   Log Message Length: 266
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.054240:   Process Data Length: 45
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.057100:   Log Message:
Jul 27 18:53:28 order tse.sh[586]: 30820106020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822D42656C65675E302E30305F302E30305F302E30305F302E30305F2D3436322E31305E2D3436322E31303A426172830E4B617373656E62656C65672D5631850210BC04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F0007010104010402023CFA020461003A0A04602615F342899562404EA3110EAED71DD0EFF308EE9D2B9DC2F7462C6B49CEDEFA35D78666BC50E82B42549A4FBF95036A028B67396E296E19ADAF2A1F1BF51577DFB9D6188506EFB94E4610D9DB47768CDBCA31C64D2885FE049F56EBF14D2D40
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.057167:   SigAlg: ecdsa-plain-SHA384
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.057194:   Signature: JhXzQomVYkBOoxEOrtcd0O/zCO6dK53C90Ysa0nO3vo114ZmvFDoK0JUmk+/lQNqAotnOW4pbhmtryofG/UVd9+51hiFBu+5TkYQ2dtHdozbyjHGTSiF/gSfVuvxTS1A
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.057249:   TSESerialNo: [B@71dc19dc
Jul 27 18:53:28 order tse.sh[586]: 2021-07-27T18:53:28.057275:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==


Jul 27 18:53:40 order tse.sh[586]: 2021-07-27T18:53:40.600492: Starte Signierung
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.187681:   Last Transaction Details
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.188929:   Log Time: 1627404823
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.188961:   Signature Counter: 15612
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.188987:   Transaction Number: 4285
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.189020:   Log Message Length: 264
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.189047:   Process Data Length: 41
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.190723:   Log Message:
Jul 27 18:53:41 order tse.sh[586]: 30820104020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F52443182294B617373656E61627363686C75737320323238202D20323032312D30372D32372031383A35333A34308310536F6E737469676572566F7267616E67850210BD04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F0007010104010402023CFC020461003A1704601B30DA7772F9430297526F8197A4031267D5EA08D3167157AF2952EDA0B010A1689EA520F1EDACB25A874E6C5119067D286D7960682985C545324ECD14B49A4D919B67D368553EE3E76EC7F828A2BB3363E1230C4E9B1133F675A59611D4D0E2
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.190784:   SigAlg: ecdsa-plain-SHA384
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.190816:   Signature: GzDad3L5QwKXUm+Bl6QDEmfV6gjTFnFXrylS7aCwEKFonqUg8e2sslqHTmxRGQZ9KG15YGgphcVFMk7NFLSaTZGbZ9NoVT7j527H+CiiuzNj4SMMTpsRM/Z1pZYR1NDi
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.190868:   TSESerialNo: [B@23c1236
Jul 27 18:53:41 order tse.sh[586]: 2021-07-27T18:53:41.190894:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==


Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.024261: Starte Signierung
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566561:   Last Transaction Details
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566663:   Log Time: 1627465066
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566690:   Signature Counter: 15617
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566715:   Transaction Number: 4286
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566816:   Log Message Length: 294
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.566845:   Process Data Length: 74
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.569540:   Log Message:
Jul 28 11:37:45 order tse.sh[586]: 30820122020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431824A313B22486569737365204B6972736368656E223B342E32300A313B224D69636B79204D617573223B332E39300A313B224D696C63687368616B65206D6974205361686E65223B342E3230830D42657374656C6C756E672D5631850210BE04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F0007010104010402023D0102046101256A0460715AE17F965C7397C8E4FB36069BEF8382A602E3DA9AA3B53BD0D5E91771D7A4D82C1CED00BD8EA84020586BC6D118383657397FCA90D455A30E5F7EEE7B4DA6F1C6BBB72A13F7FB6E861EFFDDEF5FB78DF1019927A6CBBA8250E2B725C987DD
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.570738:   SigAlg: ecdsa-plain-SHA384
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.570766:   Signature: cVrhf5Zcc5fI5Ps2Bpvvg4KmAuPamqO1O9DV6Rdx16TYLBztAL2OqEAgWGvG0Rg4Nlc5f8qQ1FWjDl9+7ntNpvHGu7cqE/f7boYe/93vX7eN8QGZJ6bLuoJQ4rclyYfd
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.570837:   TSESerialNo: [B@4863685
Jul 28 11:37:45 order tse.sh[586]: 2021-07-28T11:37:45.571200:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==


Jul 28 11:49:29 order tse.sh[586]: 2021-07-28T11:49:29.278653: Starte Signierung
Jul 28 11:49:29 order tse.sh[586]: 2021-07-28T11:49:29.287839: Start Self Test
Jul 28 11:49:42 order tse.sh[586]: 2021-07-28T11:49:42.931426: Completed Self Test
Jul 28 11:49:43 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:49:43 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:49:43 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:49:43 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:49:43 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:49:43 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:49:43 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:49:43 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:49:54 order tse.sh[586]: 2021-07-28T11:49:54.186616: Starte Signierung
Jul 28 11:49:54 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:49:54 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:49:54 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:49:54 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:49:54 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:49:54 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:49:54 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:49:54 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:50:45 order tse.sh[586]: 2021-07-28T11:50:45.316805: Starte Signierung
Jul 28 11:50:45 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:50:45 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:50:45 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:50:45 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:50:45 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:50:45 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:50:45 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:50:45 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:51:06 order tse.sh[586]: 2021-07-28T11:51:06.860579: Starte Signierung
Jul 28 11:51:06 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:51:06 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:51:06 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:51:06 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:51:06 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:51:06 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:51:06 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:51:06 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:55:50 order tse.sh[586]: 2021-07-28T11:55:50.661042: Starte Signierung
Jul 28 11:55:50 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:55:50 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:55:50 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:55:50 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:55:50 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:55:50 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:55:50 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:55:50 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:56:38 order tse.sh[586]: 2021-07-28T11:56:38.357629: Starte Signierung
Jul 28 11:56:38 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:56:38 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:56:38 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:56:38 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:56:38 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:56:38 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:56:38 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:56:38 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:56:58 order tse.sh[586]: 2021-07-28T11:56:58.894614: Starte Signierung
Jul 28 11:56:58 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:56:58 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:56:58 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:56:58 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:56:58 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:56:58 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:56:58 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:56:58 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 11:57:25 order tse.sh[586]: 2021-07-28T11:57:25.762502: Starte Signierung
Jul 28 11:57:25 order tse.sh[586]: com.secureflashcard.wormapi.WormException: WormException: WORM_ERROR_NO_TIME_SET
Jul 28 11:57:25 order tse.sh[586]:         at com.secureflashcard.wormapi.WormAPIJNI.WormStore_transaction_start(Native Method)
Jul 28 11:57:25 order tse.sh[586]:         at com.secureflashcard.wormapi.WormStore.transaction_start(WormStore.java:706)
Jul 28 11:57:25 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.signvalue(TseSignCommand.java:54)
Jul 28 11:57:25 order tse.sh[586]:         at tseconnector.commands.TseSignCommand.doCmd(TseSignCommand.java:26)
Jul 28 11:57:25 order tse.sh[586]:         at tseconnector.handler.RequestHandler.handle(RequestHandler.java:54)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.AuthFilter.doFilter(AuthFilter.java:82)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:80)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange$LinkHandler.handle(ServerImpl.java:692)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/com.sun.net.httpserver.Filter$Chain.doFilter(Filter.java:77)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Exchange.run(ServerImpl.java:664)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$DefaultExecutor.execute(ServerImpl.java:159)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.handle(ServerImpl.java:442)
Jul 28 11:57:25 order tse.sh[586]:         at jdk.httpserver/sun.net.httpserver.ServerImpl$Dispatcher.run(ServerImpl.java:408)
Jul 28 11:57:25 order tse.sh[586]:         at java.base/java.lang.Thread.run(Thread.java:829)


Jul 28 12:00:10 order systemd[1]: Stopping TSE...
Jul 28 12:00:10 order systemd[1]: tse.service: Succeeded.
Jul 28 12:00:10 order systemd[1]: Stopped TSE.
Jul 28 12:00:10 order systemd[1]: Started TSE.
Jul 28 12:00:10 order tse.sh[8679]: TSEConnectors kann folgende Parameter verarbeiten:
Jul 28 12:00:10 order tse.sh[8679]:    --version      Ausgabe der TSEConnector-Version. Wird dieses Argument eingesetzt, beendet sich der TSEConnector nach der Versionsangabe.
Jul 28 12:00:10 order tse.sh[8679]:    --pass=xyz     Das Passwort des TSEConnectors. In der OrderSprinter-Administrationsansicht einstellen!
Jul 28 12:00:10 order tse.sh[8679]:    --port=8000    Der TCP-Port, über den OrderSprinter-Core mit dem TSEConnector kommunizieren soll
Jul 28 12:00:10 order tse.sh[8679]:    --mountpoint=/media/account/SWISSBIT   Das Laufwerk/Mountpoint der Swissbit-TSE
Jul 28 12:00:10 order tse.sh[8679]:    --mode=normal  Falls der Wert auf "simulate" eingestellt wird, wird die TSE nur simuliert.
Jul 28 12:00:10 order tse.sh[8679]:    --loglevel=number  0: nur relevante Infos, 1: Signierungen, 2: umfangreiches Logging.
Jul 28 12:00:10 order tse.sh[8679]:    --showlog=number  0: kein Zugriff auf Log via Web, 1: Zugriff erlauben ohne Passwortabfrage (default: 1)
Jul 28 12:00:10 order tse.sh[8679]: Zusätzlich muss der Pfad zum Verzeichnis tseconnector_lib angegeben werden (sonst wird ein java.lang.UnsatisfiedLinkError gemeldet):
Jul 28 12:00:10 order tse.sh[8679]:    z.B. java -Djava.library.path=/usr/local/bin/tseconnector/swissbit/nativelibs/linux64 -jar tseconnector.jar mode=...
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.993150: Start TSEConnector mit diesen Parametern:
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997193:   pass=      
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997361:   port=      
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997487:   supplier=  swissbit
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997612:   mountpoint=/mnt/tse
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997779:   mode=      normal
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.997917:   loglevel=  1
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.998156:   showlog=   1
Jul 28 12:00:10 order tse.sh[8679]: 2021-07-28T12:00:10.998302: TSEConnector-Version: 2.1
Jul 28 12:00:10 order tse.sh[8679]: TSE seems to be mounted
Jul 28 12:00:11 order tse.sh[8679]: Sync Time = 1800 seconds (30 minutes)
Jul 28 12:00:11 order tse.sh[8679]: 2021-07-28T12:00:11.131456: Abgeschlossen Startup des TSEConnectors


Jul 28 12:01:04 order tse.sh[8679]: 2021-07-28T12:01:04.628556: Starte Signierung
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.205595:   Last Transaction Details
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.205912:   Log Time: 1627466465
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.206021:   Signature Counter: 15623
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.206125:   Transaction Number: 4287
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.206376:   Log Message Length: 262
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.206518:   Process Data Length: 41
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.211666:   Log Message:
Jul 28 12:01:05 order tse.sh[8679]: 30820102020102060904007F000703070101801146696E6973685472616E73616374696F6E81044F524431822942656C65675E302E30305F31322E33305F302E30305F302E30305F302E30305E31322E33303A426172830E4B617373656E62656C65672D5631850210BF04207745257BEF60FB44F946D28BED4A5F4A0EEDF339774EC433839B28A1DB417EA2300C060A04007F0007010104010402023D07020461012AE104605B796EC2B305F4C6E8A3B37D402FE62F9FCC9DBA3A6A63854700BB4B363B9923B90C22A876F2E0BCA8ECD0098DF3427977D0E5EB5FBB5FCBD0F013DCFEA302A5D5A174ADDC8E1E36601D6E701D4B2F0C70AF4F3BA6AA9CC2E91EC40785C9C2CC
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.211970:   SigAlg: ecdsa-plain-SHA384
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.212104:   Signature: W3luwrMF9Mboo7N9QC/mL5/Mnbo6amOFRwC7SzY7mSO5DCKodvLgvKjs0AmN80J5d9Dl61+7X8vQ8BPc/qMCpdWhdK3cjh42YB1ucB1LLwxwr087pqqcwukexAeFycLM
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.212318:   TSESerialNo: [B@ccc0be6
Jul 28 12:01:05 order tse.sh[8679]: 2021-07-28T12:01:05.212436:   TSEPublicKey: BCn386oaCnCLHi8eElxESIXWYME8avJZZnTxCx7acSV/3JiyI1QnwOigwt4beqICTw678eqQBGP/SpJA54z32nyJQ8eCeygkSpSO6ksJSfbBsF6K091KlTFNzSzlTj4pZg==
Übrigens... TSESerialNo scheint noch ein Pointer auf ein byte array zu sein anstatt die Seriennummer. ;-)

Viele Grüße
André
thomas_eburg
Beiträge: 28
Registriert: Fr 11. Dez 2015, 10:25
Wohnort: Frorath (Westerwald)

Re: WORM_ERROR_NO_TIME_SET

Beitrag von thomas_eburg »

Gleiches oder ähnliches Problem haben "wir" auch.
Trat auf, nachdem jemand einen anderen PC als Client ... also Kassenbedienung mit
Browser ... aufgestellt hatte. Ich vermutete damals, das die Uhrzeit von Server,
der mit Xubuntu läuft, und Client (Windows 10) nicht synchron laufen.
Da ich aber vor Ort keine Lösung fand, trotz mehrmaligem Neustart der TSE
sowohl in einem Terminal direkt mit Befehl, und auch die Tests im Browser
nichts brachten - also innerhalb der Konfiguration, hatte ich die
TSE mit der Konfiguration im Browser auf FEHLER gestellt.
(und weil es niemand wirklich interessiert hatte : läuft die immer noch auf FEHLER)
Ich müßte aber vor Ort LOG Dateien auslesen oder die Fehlermeldungen, die
ich teils in Libre Office Dokumente vor Ort kopiert hatte ... bin auf jeden
Fall an einer Lösung interessiert ...
Thomas
pichel
Administrator
Beiträge: 1154
Registriert: So 13. Sep 2015, 19:48
Wohnort: Hamburg
Kontaktdaten:

Re: WORM_ERROR_NO_TIME_SET

Beitrag von pichel »

Hallo,

um Inhalte zu signieren, frage ich nicht vor jeder Signierung bei der TSE ab, ob die Zeit noch aktuell ist. Stattdessen weiß ich ja, in welchen Zeitabständen die Uhrzeit synchronisiert werden muss und versuche damit ein paar Millisekunden einzusparen. Dabei ist es offenbar zu einer Situation gekommen, wo das nicht richtig funktioniert hat. Ich werde es mir noch anschauen. Aber in jedem Fall sollte ein Neustart des Services helfen, da dabei immer die Uhrzeit synchronisiert wird. Nur für den Hintergrund, um die Ursache einzugrenzen: kann es sein, dass vor dem Fehler längere Zeit (Wochenende?) das Kernsystem nicht benutzt wurde?

@Thomas: Der Status "Fehler" ist nur dafür gedacht, für eine KURZE Zeit bis zur Lösung zumindest weiterarbeiten zu können. Dabei wird auf dem Kassenbon die Notiz über die defekte TSE-Anbindung gedruckt und auch im DSFinvk-Export ist das für den Prüfer nachvollziehbar. Wenn das aber über längere Zeit eingestellt ist und ein Prüfer das sieht, wird es echt ungemütlich! ich kann nur empfehlen, alles auf den neuesten Stand zu updaten und wenn es nicht geht, schaue ich mir das gerne auch mal an.

Gruß,

Stefan
Stefan Pichel
Entwickler der Kassensoftware OrderSprinter (http://www.ordersprinter.de)
misery
Beiträge: 36
Registriert: Do 24. Sep 2020, 09:53

Re: WORM_ERROR_NO_TIME_SET

Beitrag von misery »

Moin Stefan,

doch, das System wird jeden Tag verwendet. Im Log oben sieht man auch, dass es erst 12 Minuten davor verwendet wurde.

Viele Grüße
André
pichel
Administrator
Beiträge: 1154
Registriert: So 13. Sep 2015, 19:48
Wohnort: Hamburg
Kontaktdaten:

Re: WORM_ERROR_NO_TIME_SET

Beitrag von pichel »

Hallo André,

ich kann zwar die eigentliche Root-Cause nicht erkennen, wieso es bei dir einmal das Problem mit der Zeitsynchronisierung gab, aber ich habe einen neuen TSEConnector gebaut, der das in jedem Fall verhindern sollte. Außerdem ist da die neue SDK-Version von Swissbit enthalten. Ich hänge die neue Version mal an, sie wird aber auch in der nächsten OrderSprinter-version enthalten sein.

Gruß,

Stefan
Dateianhänge
setup.zip
(4.24 MiB) 56-mal heruntergeladen
tseconnector.zip
(4.67 MiB) 64-mal heruntergeladen
Stefan Pichel
Entwickler der Kassensoftware OrderSprinter (http://www.ordersprinter.de)
Antworten