Project

General

Profile

Bug #790 » richclient_info.log

Jürgen Womser-Schütz, 01/10/2020 07:54 PM

 
1
2020-01-10 19:45:08,695+01 [main] INFO  o.o.richclient.LogbackConfig:81 - Configured Logback with config file from: C:\Users\Juergen\.openecard\richclient_logback.xml
2
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang C.
3
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang de.
4
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang de_DE.
5
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang C.
6
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang de.
7
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang de_DE.
8
2020-01-10 19:45:08,695+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource richclient for lang de_DE.
9
2020-01-10 19:45:08,711+01 [main] INFO  org.openecard.richclient.RichClient:144 - Starting Open eCard App 1.4.0-rc.5-SNAPSHOT ...
10
2020-01-10 19:45:08,711+01 [main] DEBUG org.openecard.richclient.RichClient:146 - Running on Windows 10 10.0 amd64.
11
2020-01-10 19:45:09,258+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:98 - Loading middleware config.
12
2020-01-10 19:45:09,258+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:135 - Reading middleware config from XML file.
13
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: rub-card.png
14
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: piv.jpg
15
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: luxtrust-v3.jpg
16
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: Lux-eID.jpg
17
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: ihk-card.jpg
18
2020-01-10 19:45:09,320+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: portuguese-eid.png
19
2020-01-10 19:45:09,336+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: SafeNet-eToken.jpg
20
2020-01-10 19:45:09,336+01 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: GeoEID.jpg
21
2020-01-10 19:45:09,351+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource recognition for lang de_DE.
22
2020-01-10 19:45:09,351+01 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:128 - Initializing CIF Repo.
23
2020-01-10 19:45:09,351+01 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:146 - Initializing RecognitionTree Repo.
24
2020-01-10 19:45:09,492+01 [main] DEBUG org.openecard.richclient.RichClient:235 - Checking if dispatcher mode should be used.
25
2020-01-10 19:45:09,492+01 [Init-File-Addons] DEBUG org.openecard.addon.FileRegistry:144 - Starting addon filesystem monitor on path: C:\Users\Juergen\.openecard\addons
26
2020-01-10 19:45:09,617+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal TR-03112 add-on.
27
2020-01-10 19:45:09,617+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal ChipGateway add-on.
28
2020-01-10 19:45:09,617+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal PIN-Management add-on.
29
2020-01-10 19:45:09,617+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal GenericCrypto add-on.
30
2020-01-10 19:45:09,632+01 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal Status add-on.
31
2020-01-10 19:45:09,632+01 [Init-Classpath-Addons] WARN  o.openecard.addon.ClasspathRegistry:89 - Skipped loading internal add-on PKCS#11, because it is not available.
32
2020-01-10 19:45:09,914+01 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:134 - Done loading CIF documents.
33
2020-01-10 19:45:09,914+01 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:138 - Done determining supported cards.
34
2020-01-10 19:45:09,914+01 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:140 - Finished initializing CIF Repo.
35
2020-01-10 19:45:10,383+01 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:159 - Finished initializing RecognitionTree Repo.
36
2020-01-10 19:45:10,617+01 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource http for lang de_DE.
37
2020-01-10 19:45:10,633+01 [main] DEBUG o.o.c.binding.http.HttpService:76 - Starting HTTP Binding on port 24727
38
2020-01-10 19:45:10,633+01 [main] DEBUG o.o.c.binding.http.HttpService:88 - Add handler [org.openecard.control.binding.http.handler.HttpAppPluginActionHandler] for ID [*]
39
2020-01-10 19:45:10,805+01 [main] INFO  org.openecard.scio.PCSCFactory:64 - Trying to initialize PCSC subsystem.
40
2020-01-10 19:45:10,852+01 [main] INFO  org.openecard.scio.PCSCFactory:66 - Successfully initialized PCSC subsystem.
41
2020-01-10 19:45:10,852+01 [main] DEBUG o.o.ifd.event.IfdEventRunner:94 - Requesting terminal names.
42
2020-01-10 19:45:10,867+01 [main] DEBUG o.o.ifd.event.IfdEventRunner:100 - Requesting status for all terminals found.
43
2020-01-10 19:45:10,883+01 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:213 - Found a terminal added event (Microsoft IFD 0).
44
2020-01-10 19:45:10,883+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.common.sal.state.SALStateCallback@3646a422.
45
2020-01-10 19:45:10,883+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.addon.EventHandler@69e153c5.
46
2020-01-10 19:45:10,883+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.richclient.gui.Status@3568f9d2.
47
2020-01-10 19:45:10,883+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: TERMINAL_ADDED
48
2020-01-10 19:45:10,883+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=Microsoft IFD 0, slot=null, ctx=8C54C0CD6038B040484D2D30C7005999
49
2020-01-10 19:45:10,883+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
50
2020-01-10 19:45:10,883+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
51
2020-01-10 19:45:10,899+01 [Update-Task] INFO  o.o.r.RichClient$UpdateTask:345 - Skipping update check for developer build.
52
2020-01-10 19:45:10,899+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:222 - Detecting initial terminal status.
53
2020-01-10 19:45:10,899+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:226 - Terminal='Microsoft IFD 0' cardPresent=false
54
2020-01-10 19:45:24,696+01 [SCIO Watcher 0] INFO  o.o.i.s.wrapper.SingleThreadChannel:139 - Card connected with protocol T=1.
55
2020-01-10 19:45:24,705+01 [IFD Watcher 0] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type GetIFDCapabilities.
56
2020-01-10 19:45:24,716+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
57
2020-01-10 19:45:24,716+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@3646a422.
58
2020-01-10 19:45:24,716+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
59
2020-01-10 19:45:24,716+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
60
2020-01-10 19:45:24,716+01 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'Microsoft IFD 0' is already connected.
61
2020-01-10 19:45:24,726+01 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.TerminalInfo:323 - Unable to request features from reader.
62
org.openecard.common.ifd.scio.SCIOException: Failed to transmit control command to the terminal 'Microsoft IFD 0'.
63
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/org.openecard.scio.PCSCCard.transmitControlCommand(PCSCCard.java:110)
64
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/org.openecard.ifd.scio.wrapper.SingleThreadChannel.lambda$transmitControlCommand$2(SingleThreadChannel.java:288)
65
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
66
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
67
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
68
	at java.base/java.lang.Thread.run(Thread.java:834)
69
Caused by: jnasmartcardio.Smartcardio$JnaPCSCException: SCardControl got response 0x32 (null: null)
70
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/jnasmartcardio.Smartcardio.check(Smartcardio.java:960)
71
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/jnasmartcardio.Smartcardio.check(Smartcardio.java:951)
72
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/jnasmartcardio.Smartcardio.access$000(Smartcardio.java:34)
73
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/jnasmartcardio.Smartcardio$JnaCard.transmitControlCommand(Smartcardio.java:620)
74
	at org.openecard.richclient@1.4.0-rc.5-SNAPSHOT/org.openecard.scio.PCSCCard.transmitControlCommand(PCSCCard.java:107)
75
	... 5 common frames omitted
76
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
77
2020-01-10 19:45:24,735+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@3646a422.
78
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
79
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:235 - Found a card insert event (Microsoft IFD 0).
80
2020-01-10 19:45:24,735+01 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:236 - Card with ATR=3B8780018031B8738401E019 inserted.
81
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.common.sal.state.SALStateCallback@3646a422.
82
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
83
2020-01-10 19:45:24,735+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.richclient.gui.Status@3568f9d2.
84
2020-01-10 19:45:24,735+01 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'Microsoft IFD 0' is already connected.
85
2020-01-10 19:45:24,735+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_INSERTED
86
2020-01-10 19:45:24,735+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=Microsoft IFD 0, slot=null, ctx=8C54C0CD6038B040484D2D30C7005999
87
2020-01-10 19:45:24,735+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/unknown, 3B8780018031B8738401E019
88
2020-01-10 19:45:24,735+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type Connect.
89
2020-01-10 19:45:24,735+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
90
2020-01-10 19:45:24,746+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
91
2020-01-10 19:45:24,746+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@3646a422.
92
2020-01-10 19:45:24,746+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
93
2020-01-10 19:45:24,746+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
94
2020-01-10 19:45:24,746+01 [IFD Watcher 1] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'Microsoft IFD 0' is already connected.
95
2020-01-10 19:45:24,760+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:222 - Detecting initial terminal status.
96
2020-01-10 19:45:24,765+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:226 - Terminal='Microsoft IFD 0' cardPresent=true
97
2020-01-10 19:45:24,765+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
98
2020-01-10 19:45:24,765+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@3646a422.
99
2020-01-10 19:45:24,765+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
100
2020-01-10 19:45:24,765+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type BeginTransaction.
101
2020-01-10 19:45:24,765+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
102
2020-01-10 19:45:24,776+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@3646a422.
103
2020-01-10 19:45:24,776+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
104
2020-01-10 19:45:24,776+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.BeginTransaction
105
2020-01-10 19:45:24,795+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
106
2020-01-10 19:45:24,795+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@3646a422.
107
2020-01-10 19:45:24,795+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
108
2020-01-10 19:45:24,795+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
109
2020-01-10 19:45:24,816+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
110
2020-01-10 19:45:24,816+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@3646a422.
111
2020-01-10 19:45:24,816+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
112
2020-01-10 19:45:24,816+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
113
2020-01-10 19:45:24,816+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
114
2020-01-10 19:45:24,840+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
115
2020-01-10 19:45:24,850+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
116
2020-01-10 19:45:24,850+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@3646a422.
117
2020-01-10 19:45:24,850+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
118
2020-01-10 19:45:24,850+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
119
2020-01-10 19:45:24,855+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
120
2020-01-10 19:45:24,855+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@3646a422.
121
2020-01-10 19:45:24,855+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
122
2020-01-10 19:45:24,855+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
123
2020-01-10 19:45:24,855+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C020003
124
2020-01-10 19:45:24,865+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
125
2020-01-10 19:45:24,865+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
126
2020-01-10 19:45:24,875+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@3646a422.
127
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
128
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
129
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
130
2020-01-10 19:45:24,875+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@3646a422.
131
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
132
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
133
2020-01-10 19:45:24,875+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
134
2020-01-10 19:45:24,895+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
135
2020-01-10 19:45:24,906+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
136
2020-01-10 19:45:24,915+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@3646a422.
137
2020-01-10 19:45:24,915+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
138
2020-01-10 19:45:24,915+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
139
2020-01-10 19:45:24,920+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
140
2020-01-10 19:45:24,920+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@3646a422.
141
2020-01-10 19:45:24,920+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
142
2020-01-10 19:45:24,920+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
143
2020-01-10 19:45:24,920+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
144
2020-01-10 19:45:24,940+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
145
2020-01-10 19:45:24,945+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
146
2020-01-10 19:45:24,945+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@3646a422.
147
2020-01-10 19:45:24,945+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
148
2020-01-10 19:45:24,945+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
149
2020-01-10 19:45:24,950+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
150
2020-01-10 19:45:24,950+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@3646a422.
151
2020-01-10 19:45:24,950+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
152
2020-01-10 19:45:24,950+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
153
2020-01-10 19:45:24,950+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20404FF
154
2020-01-10 19:45:24,955+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
155
2020-01-10 19:45:24,966+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
156
2020-01-10 19:45:24,966+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@3646a422.
157
2020-01-10 19:45:24,966+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
158
2020-01-10 19:45:24,966+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
159
2020-01-10 19:45:24,971+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
160
2020-01-10 19:45:24,971+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@3646a422.
161
2020-01-10 19:45:24,971+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
162
2020-01-10 19:45:24,971+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
163
2020-01-10 19:45:24,971+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
164
2020-01-10 19:45:24,985+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
165
2020-01-10 19:45:24,995+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
166
2020-01-10 19:45:24,995+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@3646a422.
167
2020-01-10 19:45:24,995+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
168
2020-01-10 19:45:24,995+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
169
2020-01-10 19:45:24,995+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
170
2020-01-10 19:45:24,995+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@3646a422.
171
2020-01-10 19:45:25,005+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
172
2020-01-10 19:45:25,005+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
173
2020-01-10 19:45:25,005+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
174
2020-01-10 19:45:25,026+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
175
2020-01-10 19:45:25,031+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
176
2020-01-10 19:45:25,031+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@3646a422.
177
2020-01-10 19:45:25,031+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
178
2020-01-10 19:45:25,031+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
179
2020-01-10 19:45:25,035+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
180
2020-01-10 19:45:25,035+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@3646a422.
181
2020-01-10 19:45:25,035+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
182
2020-01-10 19:45:25,035+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
183
2020-01-10 19:45:25,035+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20304FF
184
2020-01-10 19:45:25,045+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
185
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
186
2020-01-10 19:45:25,055+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@3646a422.
187
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
188
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
189
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
190
2020-01-10 19:45:25,055+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@3646a422.
191
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
192
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
193
2020-01-10 19:45:25,055+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FF04573744549442076657220312E30
194
2020-01-10 19:45:25,085+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
195
2020-01-10 19:45:25,105+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
196
2020-01-10 19:45:25,105+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@3646a422.
197
2020-01-10 19:45:25,105+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
198
2020-01-10 19:45:25,105+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
199
2020-01-10 19:45:25,115+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
200
2020-01-10 19:45:25,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@3646a422.
201
2020-01-10 19:45:25,115+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
202
2020-01-10 19:45:25,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
2020-01-10 19:45:25,115+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FD23300000045737445494420763335
204
2020-01-10 19:45:25,145+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
205
2020-01-10 19:45:25,150+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
206
2020-01-10 19:45:25,150+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@3646a422.
207
2020-01-10 19:45:25,150+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
208
2020-01-10 19:45:25,150+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
209
2020-01-10 19:45:25,155+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
210
2020-01-10 19:45:25,155+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@3646a422.
211
2020-01-10 19:45:25,155+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
212
2020-01-10 19:45:25,155+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
213
2020-01-10 19:45:25,155+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
214
2020-01-10 19:45:25,185+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
215
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
216
2020-01-10 19:45:25,195+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@3646a422.
217
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
218
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
219
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
220
2020-01-10 19:45:25,195+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@3646a422.
221
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
222
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
223
2020-01-10 19:45:25,195+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
224
2020-01-10 19:45:25,215+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
225
2020-01-10 19:45:25,215+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
226
2020-01-10 19:45:25,225+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@3646a422.
227
2020-01-10 19:45:25,225+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
228
2020-01-10 19:45:25,225+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
229
2020-01-10 19:45:25,225+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
230
2020-01-10 19:45:25,225+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@3646a422.
231
2020-01-10 19:45:25,225+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
232
2020-01-10 19:45:25,225+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
233
2020-01-10 19:45:25,230+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B00000FF
234
2020-01-10 19:45:25,255+01 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 61324F0FE828BD080FA000000167455349474E500F434941207A752044462E655369676E5100730C4F0AA000000167455349474E61094F07A0000002471001610B4F09E80704007F00070302610C4F0AA000000167455349474E6282
235
2020-01-10 19:45:25,265+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
236
2020-01-10 19:45:25,265+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@3646a422.
237
2020-01-10 19:45:25,275+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
238
2020-01-10 19:45:25,275+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type EndTransaction.
239
2020-01-10 19:45:25,275+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
240
2020-01-10 19:45:25,275+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@3646a422.
241
2020-01-10 19:45:25,275+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
242
2020-01-10 19:45:25,275+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EndTransaction
243
2020-01-10 19:45:25,285+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
244
2020-01-10 19:45:25,285+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@3646a422.
245
2020-01-10 19:45:25,285+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
246
2020-01-10 19:45:25,285+01 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Disconnect.
247
2020-01-10 19:45:25,295+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
248
2020-01-10 19:45:25,295+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@3646a422.
249
2020-01-10 19:45:25,295+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@69e153c5.
250
2020-01-10 19:45:25,295+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
251
2020-01-10 19:45:25,295+01 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
252
2020-01-10 19:45:25,295+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@3646a422.
253
2020-01-10 19:45:25,295+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@69e153c5.
254
2020-01-10 19:45:25,305+01 [IFD Watcher 1] DEBUG org.openecard.ifd.event.Recognizer:60 - Found a recognized card event (Microsoft IFD 0).
255
2020-01-10 19:45:25,305+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.common.sal.state.SALStateCallback@3646a422.
256
2020-01-10 19:45:25,305+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.addon.EventHandler@69e153c5.
257
2020-01-10 19:45:25,305+01 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.richclient.gui.Status@3568f9d2.
258
2020-01-10 19:45:25,305+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_RECOGNIZED
259
2020-01-10 19:45:25,305+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=Microsoft IFD 0, slot=null, ctx=8C54C0CD6038B040484D2D30C7005999
260
2020-01-10 19:45:25,305+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/npa.xml, null
261
2020-01-10 19:45:25,305+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
262
2020-01-10 19:45:25,305+01 [Dispatcher Event 0] INFO  o.o.c.sal.state.SALStateCallback:63 - Add ConnectionHandle to SAL:
263
ConnectionHandle:
264
  Session: 7r-TYHasMsySmGWISNMOFg
265
  ContextHandle: 8C54C0CD6038B040484D2D30C7005999
266
  IFDName: Microsoft IFD 0  SlotIndex: 0
267
  CardType: http://bsi.bund.de/cif/npa.xml
268
2020-01-10 19:45:25,310+01 [Dispatcher Event 0] DEBUG org.openecard.sal.SelectorSAL:185 - Looking up responsible SAL for handle with, ctx=8C54C0CD6038B040484D2D30C7005999, slot=null
269
2020-01-10 19:45:25,310+01 [Dispatcher Event 0] DEBUG org.openecard.sal.SelectorSAL:193 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
270
2020-01-10 19:45:25,315+01 [Dispatcher Event 0] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
271
2020-01-10 19:45:25,355+01 [Dispatcher Event 2] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
272
2020-01-10 19:45:25,355+01 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
273
2020-01-10 19:45:25,385+01 [Dispatcher Event 0] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
274
2020-01-10 19:45:25,385+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:94 - Add new entry to card states: CardStateEntry@0 {
275
  handle=  ConnectionHandle {
276
    ctx=8C54C0CD6038B040484D2D30C7005999
277
    ifd=Microsoft IFD 0
278
    idx=0
279
    slot=null
280
    app=3F00
281
    Recognition {
282
      typ=http://bsi.bund.de/cif/npa.xml
283
      ident=null
284
    }
285
  }
286
}
287
2020-01-10 19:45:25,385+01 [Dispatcher Event 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.common.sal.state.SALStateCallback@3646a422.
288
2020-01-10 19:45:25,385+01 [Dispatcher Event 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.addon.EventHandler@69e153c5.
289
2020-01-10 19:45:25,425+01 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
290
2020-01-10 19:45:36,997+01 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:262 - Found a card removed event (Microsoft IFD 0).
291
2020-01-10 19:45:36,997+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.common.sal.state.SALStateCallback@3646a422.
292
2020-01-10 19:45:36,997+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.addon.EventHandler@69e153c5.
293
2020-01-10 19:45:36,997+01 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.richclient.gui.Status@3568f9d2.
294
2020-01-10 19:45:36,997+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_REMOVED
295
2020-01-10 19:45:36,997+01 [Dispatcher Event 0] INFO  o.o.c.sal.state.SALStateCallback:75 - Remove ConnectionHandle from SAL.
296
ConnectionHandle:
297
  Session: 7r-TYHasMsySmGWISNMOFg
298
  ContextHandle: 8C54C0CD6038B040484D2D30C7005999
299
  IFDName: Microsoft IFD 0  SlotIndex: 0
300
2020-01-10 19:45:36,997+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=Microsoft IFD 0, slot=null, ctx=8C54C0CD6038B040484D2D30C7005999
301
2020-01-10 19:45:36,997+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
302
2020-01-10 19:45:36,997+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:114 - Removing CardStateEntries based on connection handle.
303
ConnectionHandle:
304
  Session: 7r-TYHasMsySmGWISNMOFg
305
  ContextHandle: 8C54C0CD6038B040484D2D30C7005999
306
  IFDName: Microsoft IFD 0  SlotIndex: 0
307
2020-01-10 19:45:36,997+01 [Dispatcher Event 2] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
308
2020-01-10 19:45:36,997+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:160 - Internal removeEntry function called with removeSlotHandles=true.
309
2020-01-10 19:45:36,997+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:165 - Removing entry from session map for channel session=7r-TYHasMsySmGWISNMOFg.
310
2020-01-10 19:45:37,013+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:168 - Removing entry from context map for ctx=8C54C0CD6038B040484D2D30C7005999.
311
2020-01-10 19:45:37,013+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:172 - Removing all entries for SlotHandles.
312
2020-01-10 19:45:37,013+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:184 - Removing all protocol instances.
313
2020-01-10 19:45:37,013+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateEntry:172 - Removing 0 protocols from card state entry.
314
2020-01-10 19:45:37,013+01 [Dispatcher Event 0] DEBUG o.o.common.sal.state.CardStateMap:187 - Removing entry from allEntries set.
315
2020-01-10 19:45:37,029+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:222 - Detecting initial terminal status.
316
2020-01-10 19:45:37,029+01 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:226 - Terminal='Microsoft IFD 0' cardPresent=false
(2-2/6)