Project

General

Profile

RE: Die Authentifizierung ist fehlgeschlagen - Es konnte ... » richclient_info.log

Logfile - Ralf Dingeldey, 02/06/2019 04:03 PM

 
1
2019-02-06 15:58:55,610+01 [main] INFO  o.o.richclient.LogbackConfig:81 - Configured Logback with config file from: /home/frosch/.openecard/richclient_logback.xml
2
2019-02-06 15:58:55,616+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang C.
3
2019-02-06 15:58:55,617+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang de.
4
2019-02-06 15:58:55,617+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang de_DE.
5
2019-02-06 15:58:55,619+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang C.
6
2019-02-06 15:58:55,619+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang de.
7
2019-02-06 15:58:55,619+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang de_DE.
8
2019-02-06 15:58:55,621+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource richclient for lang de_DE.
9
2019-02-06 15:58:55,627+01 [main] INFO  org.openecard.richclient.RichClient:145 - Starting Open eCard App 1.3.0 ...
10
2019-02-06 15:58:56,179+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:98 - Loading middleware config.
11
2019-02-06 15:58:56,179+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:135 - Reading middleware config from XML file.
12
2019-02-06 15:58:56,264+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: rub-card.png
13
2019-02-06 15:58:56,268+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: luxtrust-v3.jpg
14
2019-02-06 15:58:56,269+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: Lux-eID.jpg
15
2019-02-06 15:58:56,270+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: ihk-card.jpg
16
2019-02-06 15:58:56,270+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: portuguese-eid.png
17
2019-02-06 15:58:56,282+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: SafeNet-eToken.jpg
18
2019-02-06 15:58:56,283+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: GeoEID.jpg
19
2019-02-06 15:58:56,303+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource recognition for lang de_DE.
20
2019-02-06 15:58:56,318+01 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:128 - Initializing CIF Repo.
21
2019-02-06 15:58:56,327+01 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:146 - Initializing RecognitionTree Repo.
22
2019-02-06 15:58:56,474+01 [Init-File-Addons] DEBUG org.openecard.addon.FileRegistry:144 - Starting addon filesystem monitor on path: /home/frosch/.openecard/addons
23
2019-02-06 15:58:56,510+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource http for lang de_DE.
24
2019-02-06 15:58:56,570+01 [main] DEBUG o.o.c.binding.http.HttpService:76 - Starting HTTP Binding on port 24727
25
2019-02-06 15:58:56,578+01 [main] DEBUG o.o.c.binding.http.HttpService:88 - Add handler [org.openecard.control.binding.http.handler.HttpAppPluginActionHandler] for ID [*]
26
2019-02-06 15:58:56,661+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal TR-03112 add-on.
27
2019-02-06 15:58:56,664+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal ChipGateway add-on.
28
2019-02-06 15:58:56,671+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal PIN-Management add-on.
29
2019-02-06 15:58:56,673+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal GenericCrypto add-on.
30
2019-02-06 15:58:56,675+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal Status add-on.
31
2019-02-06 15:58:56,675+01 [Init-Classpath-Addons] WARN  o.openecard.addon.ClasspathRegistry:89 - Skipped loading internal add-on PKCS#11, because it is not available.
32
2019-02-06 15:58:56,902+01 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:134 - Done loading CIF documents.
33
2019-02-06 15:58:56,902+01 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:138 - Done determining supported cards.
34
2019-02-06 15:58:56,902+01 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:140 - Finished initializing CIF Repo.
35
2019-02-06 15:58:57,020+01 [main] DEBUG o.o.ifd.event.IfdEventRunner:94 - Requesting terminal names.
36
2019-02-06 15:58:57,029+01 [main] DEBUG org.openecard.scio.PCSCTerminals:99 - No reader available exception.
37
2019-02-06 15:58:57,029+01 [main] DEBUG o.o.ifd.event.IfdEventRunner:100 - Requesting status for all terminals found.
38
2019-02-06 15:58:57,039+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:221 - No reader available exception.
39
2019-02-06 15:58:57,045+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
40
2019-02-06 15:58:57,052+01 [Update-Task] INFO  o.o.common.util.VersionUpdateLoader:71 - Trying to load version list.
41
2019-02-06 15:58:57,537+01 [Update-Task] INFO  o.o.common.util.VersionUpdateLoader:99 - Successfully retrieved version update list.
42
2019-02-06 15:58:57,537+01 [Update-Task] INFO  o.o.r.RichClient$UpdateTask:356 - No update found, trying again later.
43
2019-02-06 15:58:58,060+01 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:159 - Finished initializing RecognitionTree Repo.
44
2019-02-06 15:58:58,545+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
45
2019-02-06 15:59:00,046+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
46
2019-02-06 15:59:01,546+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
47
2019-02-06 15:59:03,047+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
48
2019-02-06 15:59:04,547+01 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
49
2019-02-06 15:59:06,054+01 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:213 - Found a terminal added event (REINER SCT cyberJack RFID basis 00 00).
50
2019-02-06 15:59:06,057+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.addon.EventHandler@3f200884.
51
2019-02-06 15:59:06,063+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
52
2019-02-06 15:59:06,067+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.richclient.gui.Status@1d296da.
53
2019-02-06 15:59:06,071+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: TERMINAL_ADDED
54
2019-02-06 15:59:06,072+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID basis 00 00, slot=null, ctx=27B28938692419D60A2133B49D62AFFC
55
2019-02-06 15:59:06,072+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
56
2019-02-06 15:59:06,072+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
57
2019-02-06 15:59:06,073+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
58
2019-02-06 15:59:06,073+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID basis 00 00' cardPresent=false
59
2019-02-06 15:59:25,616+01 [SCIO Watcher 0] INFO  o.o.i.s.wrapper.SingleThreadChannel:139 - Card connected with protocol T=1.
60
2019-02-06 15:59:25,618+01 [IFD Watcher 0] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type GetIFDCapabilities.
61
2019-02-06 15:59:25,640+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
62
2019-02-06 15:59:25,640+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
63
2019-02-06 15:59:25,641+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
64
2019-02-06 15:59:25,641+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
65
2019-02-06 15:59:25,642+01 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID basis 00 00' is already connected.
66
2019-02-06 15:59:25,665+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
67
2019-02-06 15:59:25,665+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
68
2019-02-06 15:59:25,665+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
69
2019-02-06 15:59:25,665+01 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:235 - Found a card insert event (REINER SCT cyberJack RFID basis 00 00).
70
2019-02-06 15:59:25,666+01 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:236 - Card with ATR=3B8780018031B8738401E019 inserted.
71
2019-02-06 15:59:25,671+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.addon.EventHandler@3f200884.
72
2019-02-06 15:59:25,671+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
73
2019-02-06 15:59:25,672+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.richclient.gui.Status@1d296da.
74
2019-02-06 15:59:25,672+01 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID basis 00 00' is already connected.
75
2019-02-06 15:59:25,672+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_INSERTED
76
2019-02-06 15:59:25,672+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID basis 00 00, slot=null, ctx=27B28938692419D60A2133B49D62AFFC
77
2019-02-06 15:59:25,673+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/unknown, 3B8780018031B8738401E019
78
2019-02-06 15:59:25,673+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
79
2019-02-06 15:59:25,675+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type Connect.
80
2019-02-06 15:59:25,676+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
81
2019-02-06 15:59:25,677+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID basis 00 00' cardPresent=true
82
2019-02-06 15:59:25,678+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
83
2019-02-06 15:59:25,678+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
84
2019-02-06 15:59:25,678+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
85
2019-02-06 15:59:25,678+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
86
2019-02-06 15:59:25,678+01 [IFD Watcher 1] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID basis 00 00' is already connected.
87
2019-02-06 15:59:25,710+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
88
2019-02-06 15:59:25,711+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
89
2019-02-06 15:59:25,711+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
90
2019-02-06 15:59:25,711+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type BeginTransaction.
91
2019-02-06 15:59:25,715+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
92
2019-02-06 15:59:25,716+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
93
2019-02-06 15:59:25,716+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
94
2019-02-06 15:59:25,716+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.BeginTransaction
95
2019-02-06 15:59:25,776+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
96
2019-02-06 15:59:25,776+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
97
2019-02-06 15:59:25,777+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
98
2019-02-06 15:59:25,777+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
99
2019-02-06 15:59:25,780+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
100
2019-02-06 15:59:25,780+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
101
2019-02-06 15:59:25,780+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
102
2019-02-06 15:59:25,780+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
103
2019-02-06 15:59:25,781+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
104
2019-02-06 15:59:25,812+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
105
2019-02-06 15:59:25,830+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
106
2019-02-06 15:59:25,830+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
107
2019-02-06 15:59:25,830+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
108
2019-02-06 15:59:25,831+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
109
2019-02-06 15:59:25,833+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
110
2019-02-06 15:59:25,833+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
111
2019-02-06 15:59:25,833+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
112
2019-02-06 15:59:25,833+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
113
2019-02-06 15:59:25,835+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C020003
114
2019-02-06 15:59:25,840+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
115
2019-02-06 15:59:25,869+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
116
2019-02-06 15:59:25,870+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
117
2019-02-06 15:59:25,870+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
118
2019-02-06 15:59:25,870+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
119
2019-02-06 15:59:25,872+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
120
2019-02-06 15:59:25,873+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
121
2019-02-06 15:59:25,873+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
122
2019-02-06 15:59:25,873+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
123
2019-02-06 15:59:25,873+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
124
2019-02-06 15:59:25,885+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
125
2019-02-06 15:59:25,896+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
126
2019-02-06 15:59:25,896+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
127
2019-02-06 15:59:25,896+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
128
2019-02-06 15:59:25,896+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
129
2019-02-06 15:59:25,907+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
130
2019-02-06 15:59:25,907+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
131
2019-02-06 15:59:25,908+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
132
2019-02-06 15:59:25,908+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
133
2019-02-06 15:59:25,908+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
134
2019-02-06 15:59:25,915+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
135
2019-02-06 15:59:25,920+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
136
2019-02-06 15:59:25,920+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
137
2019-02-06 15:59:25,920+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
138
2019-02-06 15:59:25,920+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
139
2019-02-06 15:59:25,931+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
140
2019-02-06 15:59:25,931+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
141
2019-02-06 15:59:25,934+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
142
2019-02-06 15:59:25,934+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
143
2019-02-06 15:59:25,934+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20404FF
144
2019-02-06 15:59:25,939+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
145
2019-02-06 15:59:25,949+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
146
2019-02-06 15:59:25,949+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
147
2019-02-06 15:59:25,949+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
148
2019-02-06 15:59:25,949+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
149
2019-02-06 15:59:25,958+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
150
2019-02-06 15:59:25,959+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
151
2019-02-06 15:59:25,959+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
152
2019-02-06 15:59:25,959+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
153
2019-02-06 15:59:25,959+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
154
2019-02-06 15:59:25,966+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
155
2019-02-06 15:59:25,970+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
156
2019-02-06 15:59:25,971+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
157
2019-02-06 15:59:25,971+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
158
2019-02-06 15:59:25,974+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
159
2019-02-06 15:59:25,978+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
160
2019-02-06 15:59:25,978+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
161
2019-02-06 15:59:25,978+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
162
2019-02-06 15:59:25,978+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
163
2019-02-06 15:59:25,997+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
164
2019-02-06 15:59:26,005+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
165
2019-02-06 15:59:26,009+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
166
2019-02-06 15:59:26,010+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
167
2019-02-06 15:59:26,010+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
168
2019-02-06 15:59:26,010+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
169
2019-02-06 15:59:26,012+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
170
2019-02-06 15:59:26,012+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
171
2019-02-06 15:59:26,012+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
172
2019-02-06 15:59:26,013+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
173
2019-02-06 15:59:26,013+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20304FF
174
2019-02-06 15:59:26,017+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
175
2019-02-06 15:59:26,062+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
176
2019-02-06 15:59:26,062+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
177
2019-02-06 15:59:26,062+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
178
2019-02-06 15:59:26,063+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
179
2019-02-06 15:59:26,065+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
180
2019-02-06 15:59:26,065+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
181
2019-02-06 15:59:26,065+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
182
2019-02-06 15:59:26,065+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
183
2019-02-06 15:59:26,066+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FF04573744549442076657220312E30
184
2019-02-06 15:59:26,077+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
185
2019-02-06 15:59:26,095+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
186
2019-02-06 15:59:26,095+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
187
2019-02-06 15:59:26,095+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
188
2019-02-06 15:59:26,096+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
189
2019-02-06 15:59:26,098+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
190
2019-02-06 15:59:26,098+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
191
2019-02-06 15:59:26,098+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
192
2019-02-06 15:59:26,098+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
193
2019-02-06 15:59:26,099+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FD23300000045737445494420763335
194
2019-02-06 15:59:26,108+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
195
2019-02-06 15:59:26,112+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
196
2019-02-06 15:59:26,113+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
197
2019-02-06 15:59:26,113+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
198
2019-02-06 15:59:26,113+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
199
2019-02-06 15:59:26,115+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
200
2019-02-06 15:59:26,115+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
201
2019-02-06 15:59:26,115+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
202
2019-02-06 15:59:26,115+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
203
2019-02-06 15:59:26,116+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
204
2019-02-06 15:59:26,124+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
205
2019-02-06 15:59:26,131+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
206
2019-02-06 15:59:26,131+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
207
2019-02-06 15:59:26,131+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
208
2019-02-06 15:59:26,131+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
209
2019-02-06 15:59:26,134+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
210
2019-02-06 15:59:26,138+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
211
2019-02-06 15:59:26,138+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
212
2019-02-06 15:59:26,138+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
213
2019-02-06 15:59:26,139+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
214
2019-02-06 15:59:26,146+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
215
2019-02-06 15:59:26,151+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
216
2019-02-06 15:59:26,151+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
217
2019-02-06 15:59:26,151+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
218
2019-02-06 15:59:26,152+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
219
2019-02-06 15:59:26,154+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
220
2019-02-06 15:59:26,154+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
221
2019-02-06 15:59:26,154+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
222
2019-02-06 15:59:26,155+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
223
2019-02-06 15:59:26,155+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B00000FF
224
2019-02-06 15:59:26,166+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 61324F0FE828BD080FA000000167455349474E500F434941207A752044462E655369676E5100730C4F0AA000000167455349474E61094F07A0000002471001610B4F09E80704007F00070302610C4F0AA000000167455349474E6282
225
2019-02-06 15:59:26,170+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
226
2019-02-06 15:59:26,171+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
227
2019-02-06 15:59:26,171+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
228
2019-02-06 15:59:26,171+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type EndTransaction.
229
2019-02-06 15:59:26,173+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
230
2019-02-06 15:59:26,173+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
231
2019-02-06 15:59:26,173+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
232
2019-02-06 15:59:26,174+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EndTransaction
233
2019-02-06 15:59:26,188+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
234
2019-02-06 15:59:26,188+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
235
2019-02-06 15:59:26,188+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
236
2019-02-06 15:59:26,188+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Disconnect.
237
2019-02-06 15:59:26,190+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
238
2019-02-06 15:59:26,190+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@3f200884.
239
2019-02-06 15:59:26,191+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
240
2019-02-06 15:59:26,191+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
241
2019-02-06 15:59:26,196+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
242
2019-02-06 15:59:26,196+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@3f200884.
243
2019-02-06 15:59:26,196+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
244
2019-02-06 15:59:26,197+01 [IFD Watcher 1] DEBUG org.openecard.ifd.event.Recognizer:60 - Found a recognized card event (REINER SCT cyberJack RFID basis 00 00).
245
2019-02-06 15:59:26,197+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.addon.EventHandler@3f200884.
246
2019-02-06 15:59:26,198+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
247
2019-02-06 15:59:26,199+01 [Dispatcher Event 1] INFO  o.o.c.sal.state.SALStateCallback:63 - Add ConnectionHandle to SAL:
248
ConnectionHandle:
249
  Session: kYDqrk-6QLHQmhVlH3QFyg
250
  ContextHandle: 27B28938692419D60A2133B49D62AFFC
251
  IFDName: REINER SCT cyberJack RFID basis 00 00  SlotIndex: 0
252
  CardType: http://bsi.bund.de/cif/npa.xml
253
2019-02-06 15:59:26,199+01 [Dispatcher Event 1] DEBUG org.openecard.sal.SelectorSAL:185 - Looking up responsible SAL for handle with, ctx=27B28938692419D60A2133B49D62AFFC, slot=null
254
2019-02-06 15:59:26,199+01 [Dispatcher Event 1] DEBUG org.openecard.sal.SelectorSAL:193 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
255
2019-02-06 15:59:26,200+01 [Dispatcher Event 1] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
256
2019-02-06 15:59:26,205+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.richclient.gui.Status@1d296da.
257
2019-02-06 15:59:26,205+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_RECOGNIZED
258
2019-02-06 15:59:26,206+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID basis 00 00, slot=null, ctx=27B28938692419D60A2133B49D62AFFC
259
2019-02-06 15:59:26,206+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/npa.xml, null
260
2019-02-06 15:59:26,206+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
261
2019-02-06 15:59:26,248+01 [Dispatcher Event 2] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
262
2019-02-06 15:59:26,248+01 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
263
2019-02-06 15:59:26,320+01 [Dispatcher Event 1] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
264
2019-02-06 15:59:26,337+01 [Dispatcher Event 1] DEBUG o.o.common.sal.state.CardStateMap:94 - Add new entry to card states: CardStateEntry@0 {
265
  handle=  ConnectionHandle {
266
    ctx=27B28938692419D60A2133B49D62AFFC
267
    ifd=REINER SCT cyberJack RFID basis 00 00
268
    idx=0
269
    slot=null
270
    app=3F00
271
    Recognition {
272
      typ=http://bsi.bund.de/cif/npa.xml
273
      ident=null
274
    }
275
  }
276
}
277
2019-02-06 15:59:26,339+01 [Dispatcher Event 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.addon.EventHandler@3f200884.
278
2019-02-06 15:59:26,339+01 [Dispatcher Event 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.common.sal.state.SALStateCallback@76b10754.
279
2019-02-06 15:59:26,367+01 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
280
2019-02-06 16:00:09,463+01 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:88 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Fwww.kba-online.de%3A443%2Fregisterauskunft%2Fapp%2Feidstart.html%3Bjsessionid%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5%3Fref%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5 [Host: 127.0.0.1:24727, Connection: keep-alive, Cache-Control: max-age=0, Origin: https://www.kba-online.de, Upgrade-Insecure-Requests: 1, Content-Type: application/x-www-form-urlencoded, User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.81 Safari/537.36, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,image/apng,*/*;q=0.8, Accept-Encoding: gzip, deflate, br, Accept-Language: de-DE,de;q=0.9,en-US;q=0.8,en;q=0.7]
281
2019-02-06 16:00:09,465+01 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:95 - HTTP response: HTTP/1.1 403  []
282
2019-02-06 16:00:09,466+01 [Thread-8] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
283
2019-02-06 16:00:09,488+01 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:88 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, Connection: keep-alive, User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.81 Safari/537.36, Accept: text/css,*/*;q=0.1, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fwww.kba-online.de%3A443%2Fregisterauskunft%2Fapp%2Feidstart.html%3Bjsessionid%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5%3Fref%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5, Accept-Encoding: gzip, deflate, br, Accept-Language: de-DE,de;q=0.9,en-US;q=0.8,en;q=0.7]
284
2019-02-06 16:00:09,491+01 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:81 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, Connection: keep-alive, User-Agent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.81 Safari/537.36, Accept: text/css,*/*;q=0.1, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fwww.kba-online.de%3A443%2Fregisterauskunft%2Fapp%2Feidstart.html%3Bjsessionid%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5%3Fref%3DHZ3BE85FC7CA3D4E15A1F8075D8E6ECCC5, Accept-Encoding: gzip, deflate, br, Accept-Language: de-DE,de;q=0.9,en-US;q=0.8,en;q=0.7]
285
2019-02-06 16:00:09,492+01 [Thread-8] DEBUG o.o.c.b.http.handler.FileHandler:74 - Handle file request
286
2019-02-06 16:00:09,493+01 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:103 - HTTP response: HTTP/1.1 200  [Content-Type: text/css; charset=UTF-8] [Content-Type: text/css; charset=UTF-8,Chunked: false]
287
2019-02-06 16:00:09,493+01 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:104 - HTTP request handled by: org.openecard.control.binding.http.handler.FileHandler
288
2019-02-06 16:00:31,274+01 [AWT-EventQueue-0] DEBUG o.openecard.richclient.gui.Status$1:182 - Shutdown button pressed.
289
2019-02-06 16:00:31,328+01 [IFD Watcher 0] WARN  org.openecard.ifd.scio.IFD:574 - Wait interrupted by another thread.
290
java.lang.InterruptedException: null
291
	at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404)
292
	at java.util.concurrent.FutureTask.get(FutureTask.java:191)
293
	at org.openecard.ifd.scio.IFD.wait(IFD.java:539)
294
	at org.openecard.ifd.event.IfdEventManager.wait(IfdEventManager.java:122)
295
	at org.openecard.ifd.event.IfdEventRunner.run(IfdEventRunner.java:130)
296
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
297
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
298
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
299
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
300
	at java.lang.Thread.run(Thread.java:748)
301
2019-02-06 16:00:31,331+01 [IFD Watcher 0] WARN  o.o.ifd.event.IfdEventRunner:134 - IFD Wait returned with error.
302
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/ifdl#cancellationByUser
303
  ==> Wait interrupted by another thread.
304
	at org.openecard.common.ECardException.makeException(ECardException.java:64)
305
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47)
306
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69)
307
	at org.openecard.ifd.event.IfdEventManager.wait(IfdEventManager.java:125)
308
	at org.openecard.ifd.event.IfdEventRunner.run(IfdEventRunner.java:130)
309
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
310
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
311
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
312
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
313
	at java.lang.Thread.run(Thread.java:748)
314
2019-02-06 16:00:31,332+01 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:142 - Event thread interrupted.
315
java.lang.InterruptedException: sleep interrupted
316
	at java.lang.Thread.sleep(Native Method)
317
	at org.openecard.ifd.event.IfdEventRunner.run(IfdEventRunner.java:137)
318
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
319
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
320
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
321
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
322
	at java.lang.Thread.run(Thread.java:748)
323
2019-02-06 16:00:31,332+01 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:144 - Stopping IFD event thread.
(1-1/2)