richclient_info.log - Open eCard - Open eCard Development Center

Bug #766 » richclient_info.log

Log with LOGGING=DEBUG - Vladyslav Savchenko, 09/09/2019 02:29 PM

 -09-09 14:26:21,963+02 [main] INFO  o.o.richclient.LogbackConfig:81 - Configured Logback with config file from: /home/vlad/.openecard/richclient_logback.xml
 -09-09 14:26:21,969+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang C.
 -09-09 14:26:21,970+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang en.
 -09-09 14:26:21,970+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource ifd for lang en_US.
 -09-09 14:26:21,971+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang C.
 -09-09 14:26:21,971+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang en.
 -09-09 14:26:21,971+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource sal for lang en_US.
 -09-09 14:26:21,974+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource richclient for lang en.
 -09-09 14:26:21,974+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource richclient for lang en_US.
 -09-09 14:26:21,980+02 [main] INFO  org.openecard.richclient.RichClient:145 - Starting Open eCard App 1.3.0 ...
 -09-09 14:26:22,395+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:98 - Loading middleware config.
 -09-09 14:26:22,396+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:135 - Reading middleware config from XML file.
 -09-09 14:26:22,436+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: rub-card.png
 -09-09 14:26:22,438+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: luxtrust-v3.jpg
 -09-09 14:26:22,439+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: Lux-eID.jpg
 -09-09 14:26:22,440+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: ihk-card.jpg
 -09-09 14:26:22,446+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: portuguese-eid.png
 -09-09 14:26:22,447+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: SafeNet-eToken.jpg
 -09-09 14:26:22,453+02 [main] DEBUG o.o.m.sal.config.MiddlewareConfig:149 - CardImageName: GeoEID.jpg
 -09-09 14:26:22,474+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource recognition for lang en.
 -09-09 14:26:22,474+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource recognition for lang en_US.
 -09-09 14:26:22,494+02 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:128 - Initializing CIF Repo.
 -09-09 14:26:22,495+02 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:146 - Initializing RecognitionTree Repo.
 -09-09 14:26:22,626+02 [Init-File-Addons] DEBUG org.openecard.addon.FileRegistry:144 - Starting addon filesystem monitor on path: /home/vlad/.openecard/addons
 -09-09 14:26:22,718+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource http for lang en.
 -09-09 14:26:22,719+02 [main] WARN  org.openecard.common.I18n:152 - Failed to load resource http for lang en_US.
 -09-09 14:26:22,749+02 [main] DEBUG o.o.c.binding.http.HttpService:76 - Starting HTTP Binding on port 24727
 -09-09 14:26:22,753+02 [main] DEBUG o.o.c.binding.http.HttpService:88 - Add handler [org.openecard.control.binding.http.handler.HttpAppPluginActionHandler] for ID [*]
 -09-09 14:26:22,796+02 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal TR-03112 add-on.
 -09-09 14:26:22,803+02 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal ChipGateway add-on.
 -09-09 14:26:22,815+02 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal PIN-Management add-on.
 -09-09 14:26:22,818+02 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal GenericCrypto add-on.
 -09-09 14:26:22,823+02 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:94 - Loaded internal Status add-on.
 -09-09 14:26:22,823+02 [Init-Classpath-Addons] WARN  o.openecard.addon.ClasspathRegistry:89 - Skipped loading internal add-on PKCS#11, because it is not available.
 -09-09 14:26:23,262+02 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:134 - Done loading CIF documents.
 -09-09 14:26:23,262+02 [Init-CardInfo-Repo] DEBUG o.o.recognition.CardRecognitionImpl:138 - Done determining supported cards.
 -09-09 14:26:23,262+02 [Init-CardInfo-Repo] INFO  o.o.recognition.CardRecognitionImpl:140 - Finished initializing CIF Repo.
 -09-09 14:26:23,330+02 [main] DEBUG o.o.ifd.event.IfdEventRunner:94 - Requesting terminal names.
 -09-09 14:26:23,343+02 [main] DEBUG org.openecard.scio.PCSCTerminals:99 - No reader available exception.
 -09-09 14:26:23,343+02 [main] DEBUG o.o.ifd.event.IfdEventRunner:100 - Requesting status for all terminals found.
 -09-09 14:26:23,347+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:221 - No reader available exception.
 -09-09 14:26:23,349+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:23,349+02 [Update-Task] INFO  o.o.common.util.VersionUpdateLoader:71 - Trying to load version list.
 -09-09 14:26:23,854+02 [Update-Task] INFO  o.o.common.util.VersionUpdateLoader:99 - Successfully retrieved version update list.
 -09-09 14:26:23,855+02 [Update-Task] INFO  o.o.r.RichClient$UpdateTask:356 - No update found, trying again later.
 -09-09 14:26:24,524+02 [Init-RecognitionTree-Repo] INFO  o.o.recognition.CardRecognitionImpl:159 - Finished initializing RecognitionTree Repo.
 -09-09 14:26:24,850+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:26,351+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:27,852+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:29,353+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:30,854+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:32,356+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:33,857+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:35,358+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:36,859+02 [SCIO Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:415 - Waiting for PCSC system to become available again.
 -09-09 14:26:39,701+02 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:213 - Found a terminal added event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:26:39,702+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:26:39,706+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:39,706+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: TERMINAL_ADDED
 -09-09 14:26:39,707+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification TERMINAL_ADDED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:39,708+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:26:39,708+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
 -09-09 14:26:39,709+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:26:39,709+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
 -09-09 14:26:39,709+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID standard (0974243552) 00 00' cardPresent=false
 -09-09 14:26:48,860+02 [SCIO Watcher 0] INFO  o.o.i.s.wrapper.SingleThreadChannel:139 - Card connected with protocol T=1.
 -09-09 14:26:48,866+02 [IFD Watcher 0] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type GetIFDCapabilities.
 -09-09 14:26:48,878+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:48,878+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,878+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,878+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
 -09-09 14:26:48,879+02 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:26:48,902+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:48,903+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,903+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,903+02 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:235 - Found a card insert event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:26:48,904+02 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:236 - Card with ATR=3B888001E1F35E1177830000A0 inserted.
 -09-09 14:26:48,904+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:26:48,904+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,904+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_INSERTED
 -09-09 14:26:48,904+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,904+02 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:26:48,905+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:26:48,906+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/unknown, 3B888001E1F35E1177830000A0
 -09-09 14:26:48,906+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:26:48,907+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type Connect.
 -09-09 14:26:48,908+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
 -09-09 14:26:48,908+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID standard (0974243552) 00 00' cardPresent=true
 -09-09 14:26:48,911+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:48,912+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,912+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,912+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
 -09-09 14:26:48,912+02 [IFD Watcher 1] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:26:48,946+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:48,946+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,947+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,947+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type BeginTransaction.
 -09-09 14:26:48,951+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:48,951+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,951+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,952+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.BeginTransaction
 -09-09 14:26:48,964+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:48,964+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,964+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,964+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:26:48,968+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:48,968+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:48,968+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:48,968+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:26:48,969+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:26:49,192+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6F00
 -09-09 14:26:49,209+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:49,209+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:49,209+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:49,210+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:26:49,215+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:49,215+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:49,215+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:49,215+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:26:49,216+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:26:49,219+02 [IFD Watcher 1] WARN  org.openecard.ifd.scio.IFD:978 - Error during transmit.
       org.openecard.common.ifd.scio.SCIOException: Failed to transmit APDU to the card in terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.scio.PCSCChannel.transmit(PCSCChannel.java:97)
       	at org.openecard.ifd.scio.wrapper.SingleThreadChannel.lambda$transmit$1(SingleThreadChannel.java:189)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
       	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
       	at java.lang.Thread.run(Thread.java:748)
       Caused by: javax.smartcardio.CardException: sun.security.smartcardio.PCSCException: SCARD_W_REMOVED_CARD
       	at sun.security.smartcardio.ChannelImpl.doTransmit(ChannelImpl.java:219)
       	at sun.security.smartcardio.ChannelImpl.transmit(ChannelImpl.java:90)
       	at org.openecard.scio.PCSCChannel.transmit(PCSCChannel.java:93)
       	... 5 common frames omitted
       Caused by: sun.security.smartcardio.PCSCException: SCARD_W_REMOVED_CARD
       	at sun.security.smartcardio.PCSC.SCardTransmit(Native Method)
       	at sun.security.smartcardio.ChannelImpl.doTransmit(ChannelImpl.java:189)
       	... 7 common frames omitted
 -09-09 14:26:49,231+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:49,232+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:49,232+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:49,232+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:26:49,237+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:26:49,237+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:49,237+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:49,237+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:26:49,238+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:26:49,238+02 [IFD Watcher 1] WARN  org.openecard.ifd.scio.IFD:982 - Card removed during transmit.
       java.lang.IllegalStateException: Card has been removed
       	at sun.security.smartcardio.CardImpl.checkState(CardImpl.java:111)
       	at sun.security.smartcardio.ChannelImpl.checkClosed(ChannelImpl.java:60)
       	at sun.security.smartcardio.ChannelImpl.transmit(ChannelImpl.java:87)
       	at org.openecard.scio.PCSCChannel.transmit(PCSCChannel.java:93)
       	at org.openecard.ifd.scio.wrapper.SingleThreadChannel.lambda$transmit$1(SingleThreadChannel.java:189)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
       	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:26:49,248+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:26:49,249+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:26:49,249+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:26:52,420+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:88 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Fwww.fuehrungszeugnis.bund.de%2Fffw%2Fnpa-servlet%2FUQsPS4GpQP-Y6W77S0IVpQ%2FclientInvocation%3Bjsessionid%3DECBE93DC138F90493826514C9630CCFA.nodevlp61080-02 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:69.0) Gecko/20100101 Firefox/69.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: en-US,en;q=0.5, Accept-Encoding: gzip, deflate, Connection: keep-alive, Upgrade-Insecure-Requests: 1, Cookie-Installing-Permission: required]
 -09-09 14:26:52,427+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource tr03112 for lang en.
 -09-09 14:26:52,427+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource tr03112 for lang en_US.
 -09-09 14:26:52,433+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource tctoken for lang en.
 -09-09 14:26:52,433+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource tctoken for lang en_US.
 -09-09 14:26:52,434+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource pinplugin for lang en.
 -09-09 14:26:52,435+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource pinplugin for lang en_US.
 -09-09 14:26:52,435+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource pace for lang en.
 -09-09 14:26:52,435+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource pace for lang en_US.
 -09-09 14:26:52,438+02 [FuturePromise-3] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
 -09-09 14:26:52,446+02 [Thread-8] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
 -09-09 14:26:52,446+02 [Thread-8] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
 -09-09 14:26:52,603+02 [Thread-8] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
 -09-09 14:26:52,682+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource gui for lang en.
 -09-09 14:26:52,682+02 [Thread-8] WARN  org.openecard.common.I18n:152 - Failed to load resource gui for lang en_US.
 -09-09 14:26:52,768+02 [Thread-8] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 0, previous was -1.
 -09-09 14:26:52,851+02 [Instant-Return-Thread-1] DEBUG o.o.gui.swing.SwingNavigator:244 - Received event: Button.next
 -09-09 14:26:52,862+02 [Instant-Return-Thread-1] DEBUG org.openecard.gui.swing.StepFrame:257 - Trying to kill background task if it exists.
 -09-09 14:26:52,869+02 [Instant-Return-Thread-1] DEBUG org.openecard.gui.swing.StepFrame:222 - Exchange result for step 'Provide a card'.
 -09-09 14:26:52,869+02 [Thread-8] DEBUG o.o.gui.executor.ExecutionEngine:92 - Step insert-card finished with result OK.
 -09-09 14:27:14,484+02 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:262 - Found a card removed event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:27:14,485+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:27:14,485+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:14,485+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_REMOVED
 -09-09 14:27:14,485+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:14,487+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
 -09-09 14:27:14,487+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:27:14,488+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
 -09-09 14:27:14,488+02 [Dispatcher Event 2] INFO  o.o.c.sal.state.SALStateCallback:75 - Remove ConnectionHandle from SAL.
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
 -09-09 14:27:14,489+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:27:14,489+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:114 - Removing CardStateEntries based on connection handle.
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
 -09-09 14:27:14,492+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID standard (0974243552) 00 00' cardPresent=false
 -09-09 14:27:26,618+02 [SCIO Watcher 0] INFO  o.o.i.s.wrapper.SingleThreadChannel:139 - Card connected with protocol T=1.
 -09-09 14:27:26,619+02 [IFD Watcher 0] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type GetIFDCapabilities.
 -09-09 14:27:26,626+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,626+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,626+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,626+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
 -09-09 14:27:26,627+02 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:27:26,640+02 [IFD Watcher 0] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,640+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,641+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,641+02 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:235 - Found a card insert event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:27:26,642+02 [IFD Watcher 0] INFO  o.o.ifd.event.IfdEventRunner:236 - Card with ATR=3B888001E1F35E1177830000A0 inserted.
 -09-09 14:27:26,642+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:27:26,642+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,642+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_INSERTED
 -09-09 14:27:26,643+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_INSERTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,643+02 [IFD Watcher 0] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:27:26,643+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type Connect.
 -09-09 14:27:26,643+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:27:26,644+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/unknown, 3B888001E1F35E1177830000A0
 -09-09 14:27:26,644+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:27:26,645+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
 -09-09 14:27:26,645+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID standard (0974243552) 00 00' cardPresent=true
 -09-09 14:27:26,649+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,649+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,649+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,650+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
 -09-09 14:27:26,650+02 [IFD Watcher 1] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:27:26,655+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,655+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,655+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,656+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type BeginTransaction.
 -09-09 14:27:26,658+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,658+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,658+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,658+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.BeginTransaction
 -09-09 14:27:26,665+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,666+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,666+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,666+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,669+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,670+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,670+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,670+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,670+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:27:26,696+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,705+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,706+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,706+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,706+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,712+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,713+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,713+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,713+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,713+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C020003
 -09-09 14:27:26,726+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
 -09-09 14:27:26,741+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,741+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,741+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,741+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,744+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,745+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,745+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,745+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,746+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:27:26,760+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,769+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,769+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,769+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,769+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,771+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,772+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,772+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,772+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,772+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
 -09-09 14:27:26,788+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,796+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,796+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,797+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,797+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,801+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,801+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,802+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,802+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,802+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20404FF
 -09-09 14:27:26,812+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
 -09-09 14:27:26,823+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,823+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,823+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,824+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,828+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,828+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,828+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,829+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,830+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:27:26,844+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,850+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,850+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,851+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,851+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,854+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,854+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,854+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,854+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,855+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
 -09-09 14:27:26,870+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,876+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,876+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,877+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,877+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,880+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,880+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,881+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,881+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,881+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B20304FF
 -09-09 14:27:26,891+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6D00
 -09-09 14:27:26,899+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,899+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,900+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,900+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,904+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,904+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,904+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,904+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,905+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FF04573744549442076657220312E30
 -09-09 14:27:26,919+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
 -09-09 14:27:26,928+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,928+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,929+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,929+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,931+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,931+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,931+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,931+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,932+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4040C0FD23300000045737445494420763335
 -09-09 14:27:26,946+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 6A82
 -09-09 14:27:26,953+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,953+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,953+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,954+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,963+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,964+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,964+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,964+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,964+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:27:26,978+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:26,984+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:26,985+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,985+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,985+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:26,988+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:26,989+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:26,989+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:26,989+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:26,989+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4020C022F00
 -09-09 14:27:27,004+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:27,010+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:27,011+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,011+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,012+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:27,015+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:27,015+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,016+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,016+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:27,017+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00B00000FF
 -09-09 14:27:27,033+02 [IFD Watcher 1] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 61324F0FE828BD080FA000000167455349474E500F434941207A752044462E655369676E5100730C4F0AA000000167455349474E61094F07A0000002471001610B4F09E80704007F00070302610C4F0AA000000167455349474E6282
 -09-09 14:27:27,040+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:27,040+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,040+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,040+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type EndTransaction.
 -09-09 14:27:27,042+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:27,042+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,042+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,043+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EndTransaction
 -09-09 14:27:27,056+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:27,056+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,056+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,057+02 [IFD Watcher 1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Disconnect.
 -09-09 14:27:27,059+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:27,059+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,059+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,059+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
 -09-09 14:27:27,064+02 [IFD Watcher 1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:27,064+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,064+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,065+02 [IFD Watcher 1] DEBUG org.openecard.ifd.event.Recognizer:60 - Found a recognized card event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:27:27,065+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:27:27,065+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,065+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_RECOGNIZED
 -09-09 14:27:27,065+02 [IFD Watcher 1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_RECOGNIZED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,066+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:27:27,066+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:392 - RecognitionInfo: http://bsi.bund.de/cif/npa.xml, null
 -09-09 14:27:27,066+02 [Dispatcher Event 2] INFO  o.o.c.sal.state.SALStateCallback:63 - Add ConnectionHandle to SAL:
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
         CardType: http://bsi.bund.de/cif/npa.xml
 -09-09 14:27:27,066+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:27:27,067+02 [Dispatcher Event 2] DEBUG org.openecard.sal.SelectorSAL:185 - Looking up responsible SAL for handle with, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E, slot=null
 -09-09 14:27:27,067+02 [Dispatcher Event 2] DEBUG org.openecard.sal.SelectorSAL:193 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
 -09-09 14:27:27,067+02 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
 -09-09 14:27:27,109+02 [Dispatcher Event 0] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
 -09-09 14:27:27,109+02 [Dispatcher Event 0] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
 -09-09 14:27:27,133+02 [Dispatcher Event 2] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
 -09-09 14:27:27,136+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:94 - Add new entry to card states: CardStateEntry@0 {
         handle=  ConnectionHandle {
           ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
           ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00
           idx=0
           slot=null
           app=3F00
           Recognition {
             typ=http://bsi.bund.de/cif/npa.xml
             ident=null
+          }
+        }
+      }
 -09-09 14:27:27,137+02 [Dispatcher Event 2] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:27,137+02 [Dispatcher Event 2] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.common.sal.util.InsertCardStepAction@613ecbf8.
 -09-09 14:27:27,143+02 [Dispatcher Event 2] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification RECOGNIZED_CARD_ACTIVE to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:27,144+02 [Thread-8] DEBUG o.o.gui.executor.ExecutionEngine:155 - Step Action insert-card finished with result NEXT.
 -09-09 14:27:27,145+02 [Thread-8] DEBUG o.o.gui.executor.ExecutionEngine:229 - Closing UserConsentNavigator.
 -09-09 14:27:27,180+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://www.fuehrungszeugnis.bund.de/ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/clientInvocation;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02
 -09-09 14:27:27,185+02 [Dispatcher Event 0] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
 -09-09 14:27:27,224+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - No proxy found for java. Trying next one.
 -09-09 14:27:27,239+02 [Thread-8] DEBUG o.o.c.t.proxy.ProxySettingsLoader$1:80 - Firefox settings folder is {0}
 -09-09 14:27:27,241+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - Proxy found for firefox
 -09-09 14:27:27,242+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - No proxy found for java. Trying next one.
 -09-09 14:27:27,244+02 [Thread-8] DEBUG o.o.c.t.proxy.ProxySettingsLoader$1:80 - Firefox settings folder is {0}
 -09-09 14:27:27,246+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - Proxy found for firefox
 -09-09 14:27:27,246+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:27:27,246+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:27:27,286+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:27:27,369+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [www.fuehrungszeugnis.bund.de] [www.fuehrungszeugnis.bund.de]
 -09-09 14:27:27,499+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:27:27,503+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
         GET /ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/clientInvocation;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02 HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: www.fuehrungszeugnis.bund.de
         Accept: text/xml, */*;q=0.8
         Accept-Charset: utf-8, *;q=0.8
 -09-09 14:27:27,504+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:267 - Sending HTTP request.
 -09-09 14:27:27,543+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:270 - HTTP response received.
 -09-09 14:27:27,545+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 302 Found
         Date: Mon, 09 Sep 2019 12:27:27 GMT
         Server: Apache
         Content-Length: 0
         Strict-Transport-Security: max-age=63072000;
         Cache-Control: no-cache, no-store
         Strict-Transport-Security: max-age=31536000
         Location: https://eid1.eid-service.de:443/gov_autent/async?SAMLRequest=xVfZkqNGFn2fr6iQH4kqFrHWdJUDgQAJECA2oZcJlmQRq1jE8vVD9WJ3jz0e2y8TQRBBkvfcc%2B85ZJKffp7K4ukB2i6rq7cN%2BoJsnkAV1lFWJW8b2xKe6c3P7%2F%2F41PllgTWv7NCn1RncB9D1T2zXgbZf47i66oYStCZoH1kI7LPytkn7vuleYXgcx5d4AGk7VEm3gCGpsu4lGKroJQJwHI9w1fjPK86jAD1sG51u4mJj6M8e6VKUiRycxoA%2FXoP2DLpmTQT%2BeetA90E3i9723G7PbHkO3dICg%2BDMlsZIAsU5htwiHCewL1UdgUfRkChCI88ItnniV%2BZZ5fefy%2F1GEmQR%2BrLePhNZK1i5veL4Fk7qx7%2F8oQdVD%2FvdXIWbJ6FuQ%2FC5C2%2Bbvh3A5unAv23%2BhTEERgEcJaIYJ2mEoVCfiUJ0u2W28fqArtM63V9ZP8DbJvaL7iOw6wZwqLrer%2Fq3DYagzDPycVko9oqRrwT2siXR6%2BZJb%2Bu%2BDutil1VfVBna6rX2u6x7rfwSdK99%2BGqyqvKKvSCvwZdJ3atkWfrzGURZC8J%2B8%2BR8Uxj7UHjVvOpev2j6x3jN1%2BSb9y8WeP3Muv0e4Y8B%2FG8m2bz%2FWUt8gr9P9f7Ne%2FtpFeKjiO7906rV674K27npQfS9KX%2BZ9JXhOvGLzGveoMt%2BSQMOPLzWNK1e%2FxWI93v%2FyZob8EvIB9Vx%2B1K3CYwhCAojOLzirkE%2F7QtQrr741ssPoD8R9R8pV54q6NM6emKLpG6zPi3%2FBIgPOhSjn8Mg%2FKvZ4fdPUfcqg%2FlQxfXX2Kj7L5EIjDAfkVGXJT%2F9plcryP%2Bh9Lbzn2sfNM9lEqPN5nM5fJaswv9JsB%2BK6lIf3fy1NqwdhH%2B3jK%2FVcVmTgvbDSD8MOH4xgPdI820VGwmqt6mL0u9bWJSuKcbT%2FLQMNwbPJ1EyziiYH7443plKQPNaBPR%2BZ%2Fhy4CJ1Fd7hooxpcD%2BmrgTmIlIb2nE1tygnGpLcUNgeD%2BJVbXoeRcj0jDiqN9EcdNpSIU6fLrQgGIjTEUmpgIt%2BusvkkjVLoIPtTBIi0WByL1kFWz0Gu7CCWzBEBKSa13k%2FE6mJe9IlZq0FpewgnnbYaSmFGDO2N286EFfTqkRzwDOUG6EuclLcJpZ8FHc4Kyi6o8ALH0GSQzKTMyycNgFpjkdfxx8QfecmZGwGTZ%2FaWx3YNlmcdrrq7FMlxW6zEd3aG1ld4NCuyktdK9DQtaFCpJxlhYQCstx4e%2Fsqyfet%2FmHoixzwbwy8jv36OfxGwL9n7%2B9JFDXFHyr5YKbMCcmTYWIOHA0b0%2B5mV5k5Ou6gw1R1WSouT7BzXcutCi3JAVKcSAmSLDDnyj4zxDGeOM8AYsuc1HV7oII44QU6MN0uInwInPSEK4PeM03vYlQnSWOaIq5LuV3XYFK68gPpbJ2J1QccEaDJ9lPutuNbupQQQUuNbUMVrdM%2FgGg6rg4POTN7hTHYWUgyFaOZSdxb17ME7AjfZz3pyal%2Bm2B3QLOZgjO%2BItFUHAZFt50GrjMqyA%2BVC3vTGR93WbON2jgpq1bZIoSIpfvJYIN0qiVthoPHXFf2QRkrd3Gt8xgde1WxB3MgDgK3BneRwvDXe8EdJ9dPraju1L0lt3TWEkNyiXievAu16NbjxRwJb3AeKU7A4AarEFSwuyRTGBT2mut1DzOHVqb2JaQCNdcyBjXAZCO8OTmyloz4KaGJ23KRzoiB6bLtI5Nk9%2BOw7%2Fb6Q5hy1LU5Cca94yOSBRHdAbhLTUvJr7mKp5ZPcrgga4UT4nLT9Qcr1nYpomOHOauNbEt0FS8i0oTHD6syGQsmyUemXqrJ3c8nkbyd476xswUal2h0qtTZQmitoFFKUl1Y3wcEQ5gHrT3apbxnO2IxmtxqsSigmByKi1kcrBwX0Qq%2FwYxKOr4kVCMmMCnjOvd%2BQWGNaKMjafneCLBkQOS5u2Tu0QL79u546agPEHZUIgMytSm9oOclApW9G%2BHGY1ipNjum91W8ERV1n4rW5XImrIAD6aSeLxDYR24p3Agli25%2Bz9TU6OapEp1Cbcbv5kM9hhzbk5qNL%2FsYOXh1gcORahxKTNxPmH8KkWi8S17PK5EqVNCuDXkMujnWKJ8sGEcLmWBzZJvLEnppSvuIbNVS23dLhYSlXMr%2BYvqYuHTFIeZunVbu6R5qtWwSAMpSA3qiKmOvgKoWmbFxANtJszhPKuwUKM48mkM8SLrCRFfpgPYZoZ%2BqQ2EuV%2F1WifXp7MXi0bSNXQGHWhp4oaOCoMDtiTQedz5GMY%2FWfONKtIo%2Fe05g%2B8JetMKkaeaa43ldHok7DmS3Xh2INK7NtD5knDQ1oHOyhTpRshc7kHfI%2Fu5ex1z2d%2F1FUbkHiAPD6XPFmfrBVwiCuUBaNyZdxbhkoYtsPmMgjk654evV0RUdhw15S2rwUAhcRSRPwTHyHIznDOOk6zYxBYAx6GNonq%2FmUBxJzyDUnnIvkAuLfQSTDNyzy1SLoU83aZHSsrsFmOQrXuz2rFBzrVPXbaGUJAL18TFURwIwniKRDiwmR2JXokzULtsk3V%2BOOhsvJCLFUXBz6BSbVRmEWpUi8XG3%2FjSLdVtpKbu1xAc1rRvAQB4SVlGQlmvNzksNcr7KuIfKkKYyJ4hMEdoZRWIgj0o85qrex5TLIAo1cwDweQiJKtZgQS9oST5I%2FKgMriHFW2sQgzmPoy5afwI7T6E7Tbuzgp5pV2MReKvHYZaWk6NJ8dRjRorZYdh5FqBl3SeHCIO3dyWIVTPxGCZ6qMP68Zm5DcGYIzN3c6vf1RuZERcskdUlLc%2FtgbtYY0KpojwIOXV37txwy%2Bqrw57be3o1%2ByRHFV%2BCuqsqMutKioxxfKyOZ8M7KbRsQsw8j%2FKhOu2PhbwLRdu4HbDME3W%2Fco%2FzsZ1Z36BhqHBpvbvYyfK4Sund7C%2FFDhspTGZN1dOU08mM1aO5w%2BUYLyzD2oc43yLpfdZwiO2b9Mr5ESde9qoXnrfUmPnZRRB3EdnvpjmNGObhnVlQQfoWh0HZF6OJ52MWPlw0ECNjuBuEOZzVM2MfBk6%2BnG%2B2BE4PyitDwihokFzLkD8M5A5lNQc5SawYW5TQSGTbG%2Bj5kO%2Fi87ILSgunZ6SOM1i2L6Oyc6l6%2B0Afl1gaMInie0438jvtjXydqXUQSu0xBgY7kq6%2FTUt2FukdOC7NiPytff3r4P8%2BIbx%2FPWf8eLKAf%2Bek%2B%2F5v&Signature=BzvecMgiOPkq6lfBeotz83sarsFV1UaGcEZkaf9QrFpReODg40%2FKR3RdlUfK7n5L2LgAFTf6zp5cgYRRNsTW2gSrKT9PElFGGdfPoVwxNFC6fJnwxHghKcf5dVGBLieLBQ4I7w6bYxCHZBUxEPqYIvzYU6uJQamA3bMJLa1eRTnsAaUgJnRw3DfzNo1kPBmexkg5H%2FvhUG19MAOEtfmm4tBG4qT8zSHpvl6QtcUWY%2BO9ZOj8N9oV7uoURiggdlEsLZmV%2B7nq5evBoohn%2Fb7bpsyk9TvPzzZZsQ70N5nRO534284FUo%2FESuVQpuOnMVd5QnD4s8poEZiLkdbtkTO%2FoQ%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256
         X-Frame-Options: SAMEORIGIN
         Keep-Alive: timeout=10, max=500
         Connection: Keep-Alive
         Content-Type: text/plain
 -09-09 14:27:27,547+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:27:27,547+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:27:27,547+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://eid1.eid-service.de:443/gov_autent/async?SAMLRequest=xVfZkqNGFn2fr6iQH4kqFrHWdJUDgQAJECA2oZcJlmQRq1jE8vVD9WJ3jz0e2y8TQRBBkvfcc%2B85ZJKffp7K4ukB2i6rq7cN%2BoJsnkAV1lFWJW8b2xKe6c3P7%2F%2F41PllgTWv7NCn1RncB9D1T2zXgbZf47i66oYStCZoH1kI7LPytkn7vuleYXgcx5d4AGk7VEm3gCGpsu4lGKroJQJwHI9w1fjPK86jAD1sG51u4mJj6M8e6VKUiRycxoA%2FXoP2DLpmTQT%2BeetA90E3i9723G7PbHkO3dICg%2BDMlsZIAsU5htwiHCewL1UdgUfRkChCI88ItnniV%2BZZ5fefy%2F1GEmQR%2BrLePhNZK1i5veL4Fk7qx7%2F8oQdVD%2FvdXIWbJ6FuQ%2FC5C2%2Bbvh3A5unAv23%2BhTEERgEcJaIYJ2mEoVCfiUJ0u2W28fqArtM63V9ZP8DbJvaL7iOw6wZwqLrer%2Fq3DYagzDPycVko9oqRrwT2siXR6%2BZJb%2Bu%2BDutil1VfVBna6rX2u6x7rfwSdK99%2BGqyqvKKvSCvwZdJ3atkWfrzGURZC8J%2B8%2BR8Uxj7UHjVvOpev2j6x3jN1%2BSb9y8WeP3Muv0e4Y8B%2FG8m2bz%2FWUt8gr9P9f7Ne%2FtpFeKjiO7906rV674K27npQfS9KX%2BZ9JXhOvGLzGveoMt%2BSQMOPLzWNK1e%2FxWI93v%2FyZob8EvIB9Vx%2B1K3CYwhCAojOLzirkE%2F7QtQrr741ssPoD8R9R8pV54q6NM6emKLpG6zPi3%2FBIgPOhSjn8Mg%2FKvZ4fdPUfcqg%2FlQxfXX2Kj7L5EIjDAfkVGXJT%2F9plcryP%2Bh9Lbzn2sfNM9lEqPN5nM5fJaswv9JsB%2BK6lIf3fy1NqwdhH%2B3jK%2FVcVmTgvbDSD8MOH4xgPdI820VGwmqt6mL0u9bWJSuKcbT%2FLQMNwbPJ1EyziiYH7443plKQPNaBPR%2BZ%2Fhy4CJ1Fd7hooxpcD%2BmrgTmIlIb2nE1tygnGpLcUNgeD%2BJVbXoeRcj0jDiqN9EcdNpSIU6fLrQgGIjTEUmpgIt%2BusvkkjVLoIPtTBIi0WByL1kFWz0Gu7CCWzBEBKSa13k%2FE6mJe9IlZq0FpewgnnbYaSmFGDO2N286EFfTqkRzwDOUG6EuclLcJpZ8FHc4Kyi6o8ALH0GSQzKTMyycNgFpjkdfxx8QfecmZGwGTZ%2FaWx3YNlmcdrrq7FMlxW6zEd3aG1ld4NCuyktdK9DQtaFCpJxlhYQCstx4e%2Fsqyfet%2FmHoixzwbwy8jv36OfxGwL9n7%2B9JFDXFHyr5YKbMCcmTYWIOHA0b0%2B5mV5k5Ou6gw1R1WSouT7BzXcutCi3JAVKcSAmSLDDnyj4zxDGeOM8AYsuc1HV7oII44QU6MN0uInwInPSEK4PeM03vYlQnSWOaIq5LuV3XYFK68gPpbJ2J1QccEaDJ9lPutuNbupQQQUuNbUMVrdM%2FgGg6rg4POTN7hTHYWUgyFaOZSdxb17ME7AjfZz3pyal%2Bm2B3QLOZgjO%2BItFUHAZFt50GrjMqyA%2BVC3vTGR93WbON2jgpq1bZIoSIpfvJYIN0qiVthoPHXFf2QRkrd3Gt8xgde1WxB3MgDgK3BneRwvDXe8EdJ9dPraju1L0lt3TWEkNyiXievAu16NbjxRwJb3AeKU7A4AarEFSwuyRTGBT2mut1DzOHVqb2JaQCNdcyBjXAZCO8OTmyloz4KaGJ23KRzoiB6bLtI5Nk9%2BOw7%2Fb6Q5hy1LU5Cca94yOSBRHdAbhLTUvJr7mKp5ZPcrgga4UT4nLT9Qcr1nYpomOHOauNbEt0FS8i0oTHD6syGQsmyUemXqrJ3c8nkbyd476xswUal2h0qtTZQmitoFFKUl1Y3wcEQ5gHrT3apbxnO2IxmtxqsSigmByKi1kcrBwX0Qq%2FwYxKOr4kVCMmMCnjOvd%2BQWGNaKMjafneCLBkQOS5u2Tu0QL79u546agPEHZUIgMytSm9oOclApW9G%2BHGY1ipNjum91W8ERV1n4rW5XImrIAD6aSeLxDYR24p3Agli25%2Bz9TU6OapEp1Cbcbv5kM9hhzbk5qNL%2FsYOXh1gcORahxKTNxPmH8KkWi8S17PK5EqVNCuDXkMujnWKJ8sGEcLmWBzZJvLEnppSvuIbNVS23dLhYSlXMr%2BYvqYuHTFIeZunVbu6R5qtWwSAMpSA3qiKmOvgKoWmbFxANtJszhPKuwUKM48mkM8SLrCRFfpgPYZoZ%2BqQ2EuV%2F1WifXp7MXi0bSNXQGHWhp4oaOCoMDtiTQedz5GMY%2FWfONKtIo%2Fe05g%2B8JetMKkaeaa43ldHok7DmS3Xh2INK7NtD5knDQ1oHOyhTpRshc7kHfI%2Fu5ex1z2d%2F1FUbkHiAPD6XPFmfrBVwiCuUBaNyZdxbhkoYtsPmMgjk654evV0RUdhw15S2rwUAhcRSRPwTHyHIznDOOk6zYxBYAx6GNonq%2FmUBxJzyDUnnIvkAuLfQSTDNyzy1SLoU83aZHSsrsFmOQrXuz2rFBzrVPXbaGUJAL18TFURwIwniKRDiwmR2JXokzULtsk3V%2BOOhsvJCLFUXBz6BSbVRmEWpUi8XG3%2FjSLdVtpKbu1xAc1rRvAQB4SVlGQlmvNzksNcr7KuIfKkKYyJ4hMEdoZRWIgj0o85qrex5TLIAo1cwDweQiJKtZgQS9oST5I%2FKgMriHFW2sQgzmPoy5afwI7T6E7Tbuzgp5pV2MReKvHYZaWk6NJ8dRjRorZYdh5FqBl3SeHCIO3dyWIVTPxGCZ6qMP68Zm5DcGYIzN3c6vf1RuZERcskdUlLc%2FtgbtYY0KpojwIOXV37txwy%2Bqrw57be3o1%2ByRHFV%2BCuqsqMutKioxxfKyOZ8M7KbRsQsw8j%2FKhOu2PhbwLRdu4HbDME3W%2Fco%2FzsZ1Z36BhqHBpvbvYyfK4Sund7C%2FFDhspTGZN1dOU08mM1aO5w%2BUYLyzD2oc43yLpfdZwiO2b9Mr5ESde9qoXnrfUmPnZRRB3EdnvpjmNGObhnVlQQfoWh0HZF6OJ52MWPlw0ECNjuBuEOZzVM2MfBk6%2BnG%2B2BE4PyitDwihokFzLkD8M5A5lNQc5SawYW5TQSGTbG%2Bj5kO%2Fi87ILSgunZ6SOM1i2L6Oyc6l6%2B0Afl1gaMInie0438jvtjXydqXUQSu0xBgY7kq6%2FTUt2FukdOC7NiPytff3r4P8%2BIbx%2FPWf8eLKAf%2Bek%2B%2F5v&Signature=BzvecMgiOPkq6lfBeotz83sarsFV1UaGcEZkaf9QrFpReODg40%2FKR3RdlUfK7n5L2LgAFTf6zp5cgYRRNsTW2gSrKT9PElFGGdfPoVwxNFC6fJnwxHghKcf5dVGBLieLBQ4I7w6bYxCHZBUxEPqYIvzYU6uJQamA3bMJLa1eRTnsAaUgJnRw3DfzNo1kPBmexkg5H%2FvhUG19MAOEtfmm4tBG4qT8zSHpvl6QtcUWY%2BO9ZOj8N9oV7uoURiggdlEsLZmV%2B7nq5evBoohn%2Fb7bpsyk9TvPzzZZsQ70N5nRO534284FUo%2FESuVQpuOnMVd5QnD4s8poEZiLkdbtkTO%2FoQ%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256
 -09-09 14:27:27,548+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:27:27,548+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:27:27,632+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:27:27,697+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
 -09-09 14:27:27,792+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:27:27,793+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
         GET /gov_autent/async?SAMLRequest=xVfZkqNGFn2fr6iQH4kqFrHWdJUDgQAJECA2oZcJlmQRq1jE8vVD9WJ3jz0e2y8TQRBBkvfcc%2B85ZJKffp7K4ukB2i6rq7cN%2BoJsnkAV1lFWJW8b2xKe6c3P7%2F%2F41PllgTWv7NCn1RncB9D1T2zXgbZf47i66oYStCZoH1kI7LPytkn7vuleYXgcx5d4AGk7VEm3gCGpsu4lGKroJQJwHI9w1fjPK86jAD1sG51u4mJj6M8e6VKUiRycxoA%2FXoP2DLpmTQT%2BeetA90E3i9723G7PbHkO3dICg%2BDMlsZIAsU5htwiHCewL1UdgUfRkChCI88ItnniV%2BZZ5fefy%2F1GEmQR%2BrLePhNZK1i5veL4Fk7qx7%2F8oQdVD%2FvdXIWbJ6FuQ%2FC5C2%2Bbvh3A5unAv23%2BhTEERgEcJaIYJ2mEoVCfiUJ0u2W28fqArtM63V9ZP8DbJvaL7iOw6wZwqLrer%2Fq3DYagzDPycVko9oqRrwT2siXR6%2BZJb%2Bu%2BDutil1VfVBna6rX2u6x7rfwSdK99%2BGqyqvKKvSCvwZdJ3atkWfrzGURZC8J%2B8%2BR8Uxj7UHjVvOpev2j6x3jN1%2BSb9y8WeP3Muv0e4Y8B%2FG8m2bz%2FWUt8gr9P9f7Ne%2FtpFeKjiO7906rV674K27npQfS9KX%2BZ9JXhOvGLzGveoMt%2BSQMOPLzWNK1e%2FxWI93v%2FyZob8EvIB9Vx%2B1K3CYwhCAojOLzirkE%2F7QtQrr741ssPoD8R9R8pV54q6NM6emKLpG6zPi3%2FBIgPOhSjn8Mg%2FKvZ4fdPUfcqg%2FlQxfXX2Kj7L5EIjDAfkVGXJT%2F9plcryP%2Bh9Lbzn2sfNM9lEqPN5nM5fJaswv9JsB%2BK6lIf3fy1NqwdhH%2B3jK%2FVcVmTgvbDSD8MOH4xgPdI820VGwmqt6mL0u9bWJSuKcbT%2FLQMNwbPJ1EyziiYH7443plKQPNaBPR%2BZ%2Fhy4CJ1Fd7hooxpcD%2BmrgTmIlIb2nE1tygnGpLcUNgeD%2BJVbXoeRcj0jDiqN9EcdNpSIU6fLrQgGIjTEUmpgIt%2BusvkkjVLoIPtTBIi0WByL1kFWz0Gu7CCWzBEBKSa13k%2FE6mJe9IlZq0FpewgnnbYaSmFGDO2N286EFfTqkRzwDOUG6EuclLcJpZ8FHc4Kyi6o8ALH0GSQzKTMyycNgFpjkdfxx8QfecmZGwGTZ%2FaWx3YNlmcdrrq7FMlxW6zEd3aG1ld4NCuyktdK9DQtaFCpJxlhYQCstx4e%2Fsqyfet%2FmHoixzwbwy8jv36OfxGwL9n7%2B9JFDXFHyr5YKbMCcmTYWIOHA0b0%2B5mV5k5Ou6gw1R1WSouT7BzXcutCi3JAVKcSAmSLDDnyj4zxDGeOM8AYsuc1HV7oII44QU6MN0uInwInPSEK4PeM03vYlQnSWOaIq5LuV3XYFK68gPpbJ2J1QccEaDJ9lPutuNbupQQQUuNbUMVrdM%2FgGg6rg4POTN7hTHYWUgyFaOZSdxb17ME7AjfZz3pyal%2Bm2B3QLOZgjO%2BItFUHAZFt50GrjMqyA%2BVC3vTGR93WbON2jgpq1bZIoSIpfvJYIN0qiVthoPHXFf2QRkrd3Gt8xgde1WxB3MgDgK3BneRwvDXe8EdJ9dPraju1L0lt3TWEkNyiXievAu16NbjxRwJb3AeKU7A4AarEFSwuyRTGBT2mut1DzOHVqb2JaQCNdcyBjXAZCO8OTmyloz4KaGJ23KRzoiB6bLtI5Nk9%2BOw7%2Fb6Q5hy1LU5Cca94yOSBRHdAbhLTUvJr7mKp5ZPcrgga4UT4nLT9Qcr1nYpomOHOauNbEt0FS8i0oTHD6syGQsmyUemXqrJ3c8nkbyd476xswUal2h0qtTZQmitoFFKUl1Y3wcEQ5gHrT3apbxnO2IxmtxqsSigmByKi1kcrBwX0Qq%2FwYxKOr4kVCMmMCnjOvd%2BQWGNaKMjafneCLBkQOS5u2Tu0QL79u546agPEHZUIgMytSm9oOclApW9G%2BHGY1ipNjum91W8ERV1n4rW5XImrIAD6aSeLxDYR24p3Agli25%2Bz9TU6OapEp1Cbcbv5kM9hhzbk5qNL%2FsYOXh1gcORahxKTNxPmH8KkWi8S17PK5EqVNCuDXkMujnWKJ8sGEcLmWBzZJvLEnppSvuIbNVS23dLhYSlXMr%2BYvqYuHTFIeZunVbu6R5qtWwSAMpSA3qiKmOvgKoWmbFxANtJszhPKuwUKM48mkM8SLrCRFfpgPYZoZ%2BqQ2EuV%2F1WifXp7MXi0bSNXQGHWhp4oaOCoMDtiTQedz5GMY%2FWfONKtIo%2Fe05g%2B8JetMKkaeaa43ldHok7DmS3Xh2INK7NtD5knDQ1oHOyhTpRshc7kHfI%2Fu5ex1z2d%2F1FUbkHiAPD6XPFmfrBVwiCuUBaNyZdxbhkoYtsPmMgjk654evV0RUdhw15S2rwUAhcRSRPwTHyHIznDOOk6zYxBYAx6GNonq%2FmUBxJzyDUnnIvkAuLfQSTDNyzy1SLoU83aZHSsrsFmOQrXuz2rFBzrVPXbaGUJAL18TFURwIwniKRDiwmR2JXokzULtsk3V%2BOOhsvJCLFUXBz6BSbVRmEWpUi8XG3%2FjSLdVtpKbu1xAc1rRvAQB4SVlGQlmvNzksNcr7KuIfKkKYyJ4hMEdoZRWIgj0o85qrex5TLIAo1cwDweQiJKtZgQS9oST5I%2FKgMriHFW2sQgzmPoy5afwI7T6E7Tbuzgp5pV2MReKvHYZaWk6NJ8dRjRorZYdh5FqBl3SeHCIO3dyWIVTPxGCZ6qMP68Zm5DcGYIzN3c6vf1RuZERcskdUlLc%2FtgbtYY0KpojwIOXV37txwy%2Bqrw57be3o1%2ByRHFV%2BCuqsqMutKioxxfKyOZ8M7KbRsQsw8j%2FKhOu2PhbwLRdu4HbDME3W%2Fco%2FzsZ1Z36BhqHBpvbvYyfK4Sund7C%2FFDhspTGZN1dOU08mM1aO5w%2BUYLyzD2oc43yLpfdZwiO2b9Mr5ESde9qoXnrfUmPnZRRB3EdnvpjmNGObhnVlQQfoWh0HZF6OJ52MWPlw0ECNjuBuEOZzVM2MfBk6%2BnG%2B2BE4PyitDwihokFzLkD8M5A5lNQc5SawYW5TQSGTbG%2Bj5kO%2Fi87ILSgunZ6SOM1i2L6Oyc6l6%2B0Afl1gaMInie0438jvtjXydqXUQSu0xBgY7kq6%2FTUt2FukdOC7NiPytff3r4P8%2BIbx%2FPWf8eLKAf%2Bek%2B%2F5v&Signature=BzvecMgiOPkq6lfBeotz83sarsFV1UaGcEZkaf9QrFpReODg40%2FKR3RdlUfK7n5L2LgAFTf6zp5cgYRRNsTW2gSrKT9PElFGGdfPoVwxNFC6fJnwxHghKcf5dVGBLieLBQ4I7w6bYxCHZBUxEPqYIvzYU6uJQamA3bMJLa1eRTnsAaUgJnRw3DfzNo1kPBmexkg5H%2FvhUG19MAOEtfmm4tBG4qT8zSHpvl6QtcUWY%2BO9ZOj8N9oV7uoURiggdlEsLZmV%2B7nq5evBoohn%2Fb7bpsyk9TvPzzZZsQ70N5nRO534284FUo%2FESuVQpuOnMVd5QnD4s8poEZiLkdbtkTO%2FoQ%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256 HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: eid1.eid-service.de:443
         Accept: text/xml, */*;q=0.8
         Accept-Charset: utf-8, *;q=0.8
 -09-09 14:27:27,793+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:267 - Sending HTTP request.
 -09-09 14:27:27,890+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:270 - HTTP response received.
 -09-09 14:27:27,891+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 302 Found
         Server: JBoss-EAP/7
         Cache-Control: no-cache, no-store
         Content-Security-Policy: default-src 'self'
         Connection: keep-alive
         Pragma: no-cache
         Date: Mon, 09 Sep 2019 12:27:27 GMT
         Location: https://eid1.eid-service.de:443/ecardpaos/startauthentication?DateOfBirth=true&IssuingState=true&DocumentType=true&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&Nationality=true&PlaceOfBirth=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=76285dcf-ab8a-49fb-9ead-dc3640797eb3&GivenNames=true&Signature=k%2BU59JMwdeMi5MkvJ%2BPauZa80TRxiKShevkY2flsT5LdOuw5DMyTMuDqJ%2FalDqdMTKXu%2FdI4WFibK1sRuY%2BE6%2BrPLcwkRs%2FPe%2F0OV8bZd3uBwKXjWtMzQ8TLkyTk1dtjZukfGu78f6CU%2FUvThhcvG4aI2hMe87AS9vwCpnrTC1i0ml2rYfxCLxyNiC9J5vqmegixC53RRsJREVuCiPy%2FN%2BX4OF7Lyo8Kttyj2thgf8OimruGdIQ1Sb4PqWRFA%2FftfrYc%2Fu9LxfBY2Sff60I3PPpSPEyi2ozzgnNkyb1JSzY%2FeS%2BGaYlkACtXirM20sBbSi9u9MnvlsCZ0Pf6v1w5CQ%3D%3D&PlaceOfResidence=true&BirthName=true&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_4d96904946c5e9b17ab118357cd20acecbc27906
         X-Powered-By: Undertow/1
         Content-Length: 0
 -09-09 14:27:27,891+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:27:27,892+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:27:27,893+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://eid1.eid-service.de:443/ecardpaos/startauthentication?DateOfBirth=true&IssuingState=true&DocumentType=true&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&Nationality=true&PlaceOfBirth=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=76285dcf-ab8a-49fb-9ead-dc3640797eb3&GivenNames=true&Signature=k%2BU59JMwdeMi5MkvJ%2BPauZa80TRxiKShevkY2flsT5LdOuw5DMyTMuDqJ%2FalDqdMTKXu%2FdI4WFibK1sRuY%2BE6%2BrPLcwkRs%2FPe%2F0OV8bZd3uBwKXjWtMzQ8TLkyTk1dtjZukfGu78f6CU%2FUvThhcvG4aI2hMe87AS9vwCpnrTC1i0ml2rYfxCLxyNiC9J5vqmegixC53RRsJREVuCiPy%2FN%2BX4OF7Lyo8Kttyj2thgf8OimruGdIQ1Sb4PqWRFA%2FftfrYc%2Fu9LxfBY2Sff60I3PPpSPEyi2ozzgnNkyb1JSzY%2FeS%2BGaYlkACtXirM20sBbSi9u9MnvlsCZ0Pf6v1w5CQ%3D%3D&PlaceOfResidence=true&BirthName=true&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_4d96904946c5e9b17ab118357cd20acecbc27906
 -09-09 14:27:27,893+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:27:27,894+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:27:27,942+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:27:28,008+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
 -09-09 14:27:28,074+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:27:28,075+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
         GET /ecardpaos/startauthentication?DateOfBirth=true&IssuingState=true&DocumentType=true&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&Nationality=true&PlaceOfBirth=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=76285dcf-ab8a-49fb-9ead-dc3640797eb3&GivenNames=true&Signature=k%2BU59JMwdeMi5MkvJ%2BPauZa80TRxiKShevkY2flsT5LdOuw5DMyTMuDqJ%2FalDqdMTKXu%2FdI4WFibK1sRuY%2BE6%2BrPLcwkRs%2FPe%2F0OV8bZd3uBwKXjWtMzQ8TLkyTk1dtjZukfGu78f6CU%2FUvThhcvG4aI2hMe87AS9vwCpnrTC1i0ml2rYfxCLxyNiC9J5vqmegixC53RRsJREVuCiPy%2FN%2BX4OF7Lyo8Kttyj2thgf8OimruGdIQ1Sb4PqWRFA%2FftfrYc%2Fu9LxfBY2Sff60I3PPpSPEyi2ozzgnNkyb1JSzY%2FeS%2BGaYlkACtXirM20sBbSi9u9MnvlsCZ0Pf6v1w5CQ%3D%3D&PlaceOfResidence=true&BirthName=true&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_4d96904946c5e9b17ab118357cd20acecbc27906 HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: eid1.eid-service.de:443
         Accept: text/xml, */*;q=0.8
         Accept-Charset: utf-8, *;q=0.8
 -09-09 14:27:28,075+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:267 - Sending HTTP request.
 -09-09 14:27:28,130+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:270 - HTTP response received.
 -09-09 14:27:28,131+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 200 OK
         Server: JBoss-EAP/7
         Content-Security-Policy: default-src 'self'
         Connection: keep-alive
         Date: Mon, 09 Sep 2019 12:27:28 GMT
         X-Powered-By: Undertow/1
         Content-Type: text/xml;charset=utf-8
         Content-Length: 652
 -09-09 14:27:28,132+02 [Thread-8] DEBUG o.o.binding.tctoken.TCTokenContext:90 - Received TCToken:
       <TCTokenType>
       <ServerAddress>https://eid2.eid-service.de:443/ecardpaos/paosreceiver</ServerAddress>
       <SessionIdentifier>76285dcf-ab8a-49fb-9ead-dc3640797eb3</SessionIdentifier>
       <RefreshAddress>https://eid1.eid-service.de:443/gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906</RefreshAddress>
       <CommunicationErrorAddress/>
       <Binding>urn:liberty:paos:2006-08</Binding>
       <PathSecurity-Protocol>urn:ietf:rfc:4279</PathSecurity-Protocol>
       <PathSecurity-Parameters>
       <PSK>bcb821a19244e25b1bd0ba845a71281ca1ee66fb9fe6dc5b90779f05c0b4bc3b3c7b163352354340547ccbd8fb0e1d7e3e9246a0715ac11aefe83954b0a09bbf</PSK>
       </PathSecurity-Parameters>
       </TCTokenType>
 -09-09 14:27:28,133+02 [Thread-8] DEBUG o.o.binding.tctoken.TCTokenContext:92 - Cleaned up TCToken:
       <TCTokenType>
       <ServerAddress>https://eid2.eid-service.de:443/ecardpaos/paosreceiver</ServerAddress>
       <SessionIdentifier>76285dcf-ab8a-49fb-9ead-dc3640797eb3</SessionIdentifier>
       <RefreshAddress>https://eid1.eid-service.de:443/gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906</RefreshAddress>
       <CommunicationErrorAddress/>
       <Binding>urn:liberty:paos:2006-08</Binding>
       <PathSecurity-Protocol>urn:ietf:rfc:4279</PathSecurity-Protocol>
       <PathSecurity-Parameters>
       <PSK>bcb821a19244e25b1bd0ba845a71281ca1ee66fb9fe6dc5b90779f05c0b4bc3b3c7b163352354340547ccbd8fb0e1d7e3e9246a0715ac11aefe83954b0a09bbf</PSK>
       </PathSecurity-Parameters>
       </TCTokenType>
 -09-09 14:27:28,152+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,152+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,152+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,152+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationPath
 -09-09 14:27:28,157+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,157+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,157+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,159+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,159+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,159+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,159+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
 -09-09 14:27:28,160+02 [Thread-8] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type Connect.
 -09-09 14:27:28,163+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,163+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,164+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,164+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
 -09-09 14:27:28,164+02 [Thread-8] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:27:28,168+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,168+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,168+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,170+02 [Thread-8] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:28,172+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,172+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,173+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,173+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:28,173+02 [Thread-8] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 00A4000C023F00
 -09-09 14:27:28,191+02 [Thread-8] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:28,201+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,201+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,202+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,202+02 [Thread-8] DEBUG o.o.common.sal.state.CardStateMap:94 - Add new entry to card states: CardStateEntry@1 {
         handle=  ConnectionHandle {
           ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
           ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00
           idx=0
           slot=0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5
           app=3F00
           Recognition {
             typ=http://bsi.bund.de/cif/npa.xml
             ident=null
+          }
+        }
+      }
 -09-09 14:27:28,207+02 [Thread-8] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,208+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,208+02 [Thread-8] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,215+02 [PAOS] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:27:28,215+02 [PAOS] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:27:28,236+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:470 - Opening connection to PAOS server.
 -09-09 14:27:28,237+02 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:27:28,238+02 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:27:28,386+02 [PAOS] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eid2.eid-service.de] [eid2.eid-service.de]
 -09-09 14:27:28,440+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:473 - Connection to PAOS server established.
 -09-09 14:27:28,447+02 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
         POST /ecardpaos/paosreceiver HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: eid2.eid-service.de:443
         PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit";"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework";"urn:iso:std:iso-iec:24727:tech:schema:StartSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe";"urn:iso:std:iso-iec:24727:tech:schema:DataSetList";"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect";"urn:iso:std:iso-iec:24727:tech:schema:DSIList";"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite";"urn:iso:std:iso-iec:24727:tech:schema:DSIRead";"urn:iso:std:iso-iec:24727:tech:schema:Encipher";"urn:iso:std:iso-iec:24727:tech:schema:Decipher";"urn:iso:std:iso-iec:24727:tech:schema:GetRandom";"urn:iso:std:iso-iec:24727:tech:schema:Sign";"urn:iso:std:iso-iec:24727:tech:schema:VerifySignature";"urn:iso:std:iso-iec:24727:tech:schema:VerifyCertificate";"urn:iso:std:iso-iec:24727:tech:schema:DIDList";"urn:iso:std:iso-iec:24727:tech:schema:DIDGet";"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate";"urn:iso:std:iso-iec:24727:tech:schema:ACLList";"urn:iso:std:iso-iec:24727:tech:schema:Hash"
         Accept: text/xml, application/xml, application/vnd.paos+xml
 -09-09 14:27:28,485+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:268 - Message sent:
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
         <Header>
           <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
             <Version>urn:liberty:paos:2006-08</Version>
             <EndpointReference>
               <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
               <MetaData>
                 <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
               </MetaData>
             </EndpointReference>
           </PAOS>
           <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
             <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
           </ReplyTo>
           <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:aebd4616-3981-4268-b570-6ccfa3344dbc</MessageID>
         </Header>
         <Body>
           <ns3:StartPAOS xmlns="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns10="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns11="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns12="http://www.w3.org/2001/04/xmlenc#" xmlns:ns13="http://ws.openecard.org/schema" xmlns:ns14="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns15="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.w3.org/2000/09/xmldsig#" xmlns:ns3="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns4="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns5="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns6="http://www.w3.org/2009/xmlenc11#" xmlns:ns7="http://www.setcce.org/schemas/ers" xmlns:ns8="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#" xmlns:ns9="http://uri.etsi.org/01903/v1.3.2#" Profile="http://www.bsi.bund.de/ecard/api/1.1">
             <ns3:SessionIdentifier>76285dcf-ab8a-49fb-9ead-dc3640797eb3</ns3:SessionIdentifier>
             <ns3:ConnectionHandle>
               <ns3:ChannelHandle>
                 <ns3:SessionIdentifier>YSrUDVv7oNmSYhQhrh1I6A</ns3:SessionIdentifier>
               </ns3:ChannelHandle>
               <ns3:ContextHandle>2C6E58C3DA4AA0A1D51B32F6F0E78A0E</ns3:ContextHandle>
               <ns3:IFDName>REINER SCT cyberJack RFID standard (0974243552) 00 00</ns3:IFDName>
               <ns3:SlotIndex>0</ns3:SlotIndex>
               <ns3:CardApplication>3F00</ns3:CardApplication>
               <ns3:SlotHandle>0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5</ns3:SlotHandle>
               <ns3:RecognitionInfo>
                 <ns3:CardType>http://bsi.bund.de/cif/npa.xml</ns3:CardType>
               </ns3:RecognitionInfo>
             </ns3:ConnectionHandle>
             <ns3:UserAgent>
               <ns3:Name>Open eCard App</ns3:Name>
               <ns3:VersionMajor>1</ns3:VersionMajor>
               <ns3:VersionMinor>3</ns3:VersionMinor>
               <ns3:VersionSubminor>0</ns3:VersionSubminor>
             </ns3:UserAgent>
             <ns3:SupportedAPIVersions>
               <ns3:Major>1</ns3:Major>
               <ns3:Minor>1</ns3:Minor>
               <ns3:Subminor>5</ns3:Subminor>
             </ns3:SupportedAPIVersions>
             <ns3:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14</ns3:SupportedDIDProtocols>
             <ns3:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14.2</ns3:SupportedDIDProtocols>
             <ns3:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.25</ns3:SupportedDIDProtocols>
             <ns3:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.9</ns3:SupportedDIDProtocols>
           </ns3:StartPAOS>
         </Body>
       </Envelope>
 -09-09 14:27:28,486+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:363 - Sending HTTP request.
 -09-09 14:27:28,733+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:365 - HTTP response received.
 -09-09 14:27:28,733+02 [PAOS] WARN  org.openecard.transport.paos.PAOS:495 - The PAOS endpoint sent the http status code 200 which does not conform to the PAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
 -09-09 14:27:28,733+02 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 200 OK
         Server: JBoss-EAP/7
         Content-Security-Policy: default-src 'self'
         Connection: keep-alive
         Date: Mon, 09 Sep 2019 12:27:28 GMT
         X-Powered-By: Undertow/1
         Content-Type: application/vnd.paos+xml
         Content-Length: 8038
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
          <soap:Header>
             <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id18224" refToMessageID="76285dcf-ab8a-49fb-9ead-dc3640797eb3"/>
             <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:aebd4616-3981-4268-b570-6ccfa3344dbc</RelatesTo>
             <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:9cd0f519-5113-4c90-b79a-19c7edd6ecfe</MessageID>
          </soap:Header>
          <soap:Body>
          <ns2:DIDAuthenticate xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="urn:liberty:id-sis-pp:2005-05">
             <ns2:ConnectionHandle>
               <ns2:ChannelHandle>
                 <ns2:SessionIdentifier>YSrUDVv7oNmSYhQhrh1I6A</ns2:SessionIdentifier>
               </ns2:ChannelHandle>
               <ns2:ContextHandle>2C6E58C3DA4AA0A1D51B32F6F0E78A0E</ns2:ContextHandle>
               <ns2:IFDName>REINER SCT cyberJack RFID standard (0974243552) 00 00</ns2:IFDName>
               <ns2:SlotIndex>0</ns2:SlotIndex>
               <ns2:CardApplication>3F00</ns2:CardApplication>
               <ns2:SlotHandle>0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5</ns2:SlotHandle>
               <ns2:RecognitionInfo>
                 <ns2:CardType>http://bsi.bund.de/cif/npa.xml</ns2:CardType>
               </ns2:RecognitionInfo>
             </ns2:ConnectionHandle>
             <ns2:DIDName>PIN</ns2:DIDName>
             <ns2:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="ns2:EAC1InputType">
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201487f4e8201005f2901004210444544566549444454523130313434307f494f060a04007f000702020202038641048a7cd5aa633444fc8c0f0f28407c40c43b89903a2241fe5039d25b339eb6285c9a8b4823f9ad4cee7692c85688263e4ba4e3ebb157d6e291ab266fcbc23b50c75f200e44453030303032313830323636397f4c12060904007f00070301020253050001139b005f25060109000900095f2406010900090100655e732d060904007f0007030103028020497ea2b8b286fc3f57624f9bc452d1e1453f4814c243c2b22b3bd217324b55e7732d060904007f00070301030180202d30547d562e411ec0bf42d6d4a802eab9e91948b57f2a363c38b83dff39748e5f374095aab3c54f0bf7b2f4c69b5c3e2a10a6b4f079ef2b2caccb4aa3b44e04e4f6405fd69d3c52f86ceddafd97f636b916d9669f1e257d42794943b563217e0db38a</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130327f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a78641048925419fc7f194922cfc6b8dd25ae6a19c1b59216e6cf06270e5d75cfd64205f55cf867bbfefeefd6e680e1fd197f18ab684484901362568efc9adb5c6018d728701015f200e44454356434165494430303130337f4c12060904007f0007030102025305fc0f13ffff5f25060102010200035f24060105010200035f37404d6f08a86a4f18409f6685387dd3c6a7ff5d68ea4f7714a861bbb3bb721d05d3014adf1763c9292f715d8e94ee9b3e1b73ab1382414ebf39dfb3b0fb6c09dbeb</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130337f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a7864104241d8627338b64f20077ffd558909a096c635ddb222852038eaae642e869a40173d588f817d95db2a6a0f077ea5ee63596a20f85bc3cb176d2f98d88d90219aa8701015f200e44454356434165494430303130347f4c12060904007f0007030102025305fc0f13ffff5f25060105000901045f24060108000901045f3740313a81ed8734e7a8c45f16b55fb603e63027b7f44c2de3a8e782552d35949db221ca33bd41a01da6a1288c7885714fc3a03fa45683b75d3884930ec6738af8a0</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130347f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a786410459d1a08a2572022e17fed831982a9cb904518003cf83dabed88dbed28963768b88dba22a153558bd21ddaa99318378b7cd7ebfe849557cd43f8526abe72734538701015f200e44454356434165494430303130357f4c12060904007f0007030102025305fc0f13ffff5f25060108000601025f24060201000601025f37402fbeeae1b1ade6cfb6cab9d9b19f6a6efacb7d9701997f43cf608bf72cf496f485f691e75190dec25c672b92cf9ba917883659a6dc16793d22bbec4019c616cc</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f2181e77f4e81a05f290100420e44454356434165494430303130357f494f060a04007f000702020202038641045caa438d92fd3df22b6a58d438c1e6a6a30856228080b9ee2a8cb42694bc828a1283a21a38b78d9ca6b036aa69fc9797180ab27ee0d4114a2fc6f1bad2e8a2ab5f2010444544566549444454523130313434307f4c12060904007f0007030102025305400513ff975f25060109000702095f24060109010002075f374020df5b1be357da98df1377821739bc4d87a5275f5eb6196403dcbba1413840cf9deda4b6c420cc140313baa4225e89d5087e28a235a398cfa78f5bd8c6f60a92</Certificate>
               <CertificateDescription xmlns="urn:iso:std:iso-iec:24727:tech:schema">308202e5060a04007f00070301030101a10e0c0c442d547275737420476d6248a2181316687474703a2f2f7777772e642d74727573742e6e6574a3170c1542756e646573616d742066c3bc72204a757374697aa42a132868747470733a2f2f7777772e6675656872756e67737a6575676e69732e62756e642e64652f666677a58201d80c8201d44e616d652c20416e7363687269667420756e6420452d4d61696c2d4164726573736520646573204469656e737465616e626965746572733a0d0a42756e646573616d742066c3bc72204a757374697a0d0a4164656e61756572616c6c65652039392d3130330d0a353331313320426f6e6e0d0a72656769737465726175736b756e66742d6f6e6c696e654062666a2e62756e642e64650d0a0d0a4765736368c3a46674737a7765636b3a0d0a4f6e6c696e652d416e74726167206175662045727465696c756e6720766f6e2046c3bc6872756e67737a6575676e697373656e20756e6420476577657262657a656e7472616c72656769737465726175736bc3bc6e6674656e0d0a0d0a5a757374c3a46e6469676520446174656e73636875747a626568c3b67264653a0d0a4469652042756e64657362656175667472616774652066c3bc722064656e20446174656e73636875747a20756e642064696520496e666f726d6174696f6e7366726569686569740d0a4875736172656e73747261c39f652033300d0a353331313720426f6e6e0d0a303232382d3939373739392d300d0a706f73747374656c6c6540626664692e62756e642e64650d0a687474703a2f2f7777772e626664692e62756e642e64650d0aa7818b318188042008198a75e92bcc93cdff9383603d686b153b1c589ed3e0096a408f5e2e711c6c042087942ed74e7bd9d8ec866d8d76cb4b8d71b5b6b4863b7d48a1586a8603b9627f0420c9910fa727feebc2a638810e5258a32c53e270b12d42c44c98a64e574b0e95da0420deb9f8982832998110e24cc83c7d9abeb37ae9661ccb3060035e698fc8737d94</CertificateDescription>
               <RequiredCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050001139b00</RequiredCHAT>
               <OptionalCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000000000</OptionalCHAT>
               <AuthenticatedAuxiliaryData xmlns="urn:iso:std:iso-iec:24727:tech:schema">67177315060904007f00070301040253083230313930393039</AuthenticatedAuxiliaryData>
               <TransactionInfo xmlns="urn:iso:std:iso-iec:24727:tech:schema"/>
             </ns2:AuthenticationProtocolData>
           </ns2:DIDAuthenticate>
         </soap:Body>
       </soap:Envelope>
 -09-09 14:27:28,736+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:224 - Message received:
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
         <soap:Header>
           <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id18224" refToMessageID="76285dcf-ab8a-49fb-9ead-dc3640797eb3"/>
           <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:aebd4616-3981-4268-b570-6ccfa3344dbc</RelatesTo>
           <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:9cd0f519-5113-4c90-b79a-19c7edd6ecfe</MessageID>
         </soap:Header>
         <soap:Body>
           <ns2:DIDAuthenticate xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="urn:liberty:id-sis-pp:2005-05">
             <ns2:ConnectionHandle>
               <ns2:ChannelHandle>
                 <ns2:SessionIdentifier>YSrUDVv7oNmSYhQhrh1I6A</ns2:SessionIdentifier>
               </ns2:ChannelHandle>
               <ns2:ContextHandle>2C6E58C3DA4AA0A1D51B32F6F0E78A0E</ns2:ContextHandle>
               <ns2:IFDName>REINER SCT cyberJack RFID standard (0974243552) 00 00</ns2:IFDName>
               <ns2:SlotIndex>0</ns2:SlotIndex>
               <ns2:CardApplication>3F00</ns2:CardApplication>
               <ns2:SlotHandle>0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5</ns2:SlotHandle>
               <ns2:RecognitionInfo>
                 <ns2:CardType>http://bsi.bund.de/cif/npa.xml</ns2:CardType>
               </ns2:RecognitionInfo>
             </ns2:ConnectionHandle>
             <ns2:DIDName>PIN</ns2:DIDName>
             <ns2:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="ns2:EAC1InputType">
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201487f4e8201005f2901004210444544566549444454523130313434307f494f060a04007f000702020202038641048a7cd5aa633444fc8c0f0f28407c40c43b89903a2241fe5039d25b339eb6285c9a8b4823f9ad4cee7692c85688263e4ba4e3ebb157d6e291ab266fcbc23b50c75f200e44453030303032313830323636397f4c12060904007f00070301020253050001139b005f25060109000900095f2406010900090100655e732d060904007f0007030103028020497ea2b8b286fc3f57624f9bc452d1e1453f4814c243c2b22b3bd217324b55e7732d060904007f00070301030180202d30547d562e411ec0bf42d6d4a802eab9e91948b57f2a363c38b83dff39748e5f374095aab3c54f0bf7b2f4c69b5c3e2a10a6b4f079ef2b2caccb4aa3b44e04e4f6405fd69d3c52f86ceddafd97f636b916d9669f1e257d42794943b563217e0db38a</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130327f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a78641048925419fc7f194922cfc6b8dd25ae6a19c1b59216e6cf06270e5d75cfd64205f55cf867bbfefeefd6e680e1fd197f18ab684484901362568efc9adb5c6018d728701015f200e44454356434165494430303130337f4c12060904007f0007030102025305fc0f13ffff5f25060102010200035f24060105010200035f37404d6f08a86a4f18409f6685387dd3c6a7ff5d68ea4f7714a861bbb3bb721d05d3014adf1763c9292f715d8e94ee9b3e1b73ab1382414ebf39dfb3b0fb6c09dbeb</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130337f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a7864104241d8627338b64f20077ffd558909a096c635ddb222852038eaae642e869a40173d588f817d95db2a6a0f077ea5ee63596a20f85bc3cb176d2f98d88d90219aa8701015f200e44454356434165494430303130347f4c12060904007f0007030102025305fc0f13ffff5f25060105000901045f24060108000901045f3740313a81ed8734e7a8c45f16b55fb603e63027b7f44c2de3a8e782552d35949db221ca33bd41a01da6a1288c7885714fc3a03fa45683b75d3884930ec6738af8a0</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130347f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a786410459d1a08a2572022e17fed831982a9cb904518003cf83dabed88dbed28963768b88dba22a153558bd21ddaa99318378b7cd7ebfe849557cd43f8526abe72734538701015f200e44454356434165494430303130357f4c12060904007f0007030102025305fc0f13ffff5f25060108000601025f24060201000601025f37402fbeeae1b1ade6cfb6cab9d9b19f6a6efacb7d9701997f43cf608bf72cf496f485f691e75190dec25c672b92cf9ba917883659a6dc16793d22bbec4019c616cc</Certificate>
               <Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f2181e77f4e81a05f290100420e44454356434165494430303130357f494f060a04007f000702020202038641045caa438d92fd3df22b6a58d438c1e6a6a30856228080b9ee2a8cb42694bc828a1283a21a38b78d9ca6b036aa69fc9797180ab27ee0d4114a2fc6f1bad2e8a2ab5f2010444544566549444454523130313434307f4c12060904007f0007030102025305400513ff975f25060109000702095f24060109010002075f374020df5b1be357da98df1377821739bc4d87a5275f5eb6196403dcbba1413840cf9deda4b6c420cc140313baa4225e89d5087e28a235a398cfa78f5bd8c6f60a92</Certificate>
               <CertificateDescription xmlns="urn:iso:std:iso-iec:24727:tech:schema">308202e5060a04007f00070301030101a10e0c0c442d547275737420476d6248a2181316687474703a2f2f7777772e642d74727573742e6e6574a3170c1542756e646573616d742066c3bc72204a757374697aa42a132868747470733a2f2f7777772e6675656872756e67737a6575676e69732e62756e642e64652f666677a58201d80c8201d44e616d652c20416e7363687269667420756e6420452d4d61696c2d4164726573736520646573204469656e737465616e626965746572733a0d0a42756e646573616d742066c3bc72204a757374697a0d0a4164656e61756572616c6c65652039392d3130330d0a353331313320426f6e6e0d0a72656769737465726175736b756e66742d6f6e6c696e654062666a2e62756e642e64650d0a0d0a4765736368c3a46674737a7765636b3a0d0a4f6e6c696e652d416e74726167206175662045727465696c756e6720766f6e2046c3bc6872756e67737a6575676e697373656e20756e6420476577657262657a656e7472616c72656769737465726175736bc3bc6e6674656e0d0a0d0a5a757374c3a46e6469676520446174656e73636875747a626568c3b67264653a0d0a4469652042756e64657362656175667472616774652066c3bc722064656e20446174656e73636875747a20756e642064696520496e666f726d6174696f6e7366726569686569740d0a4875736172656e73747261c39f652033300d0a353331313720426f6e6e0d0a303232382d3939373739392d300d0a706f73747374656c6c6540626664692e62756e642e64650d0a687474703a2f2f7777772e626664692e62756e642e64650d0aa7818b318188042008198a75e92bcc93cdff9383603d686b153b1c589ed3e0096a408f5e2e711c6c042087942ed74e7bd9d8ec866d8d76cb4b8d71b5b6b4863b7d48a1586a8603b9627f0420c9910fa727feebc2a638810e5258a32c53e270b12d42c44c98a64e574b0e95da0420deb9f8982832998110e24cc83c7d9abeb37ae9661ccb3060035e698fc8737d94</CertificateDescription>
               <RequiredCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050001139b00</RequiredCHAT>
               <OptionalCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000000000</OptionalCHAT>
               <AuthenticatedAuxiliaryData xmlns="urn:iso:std:iso-iec:24727:tech:schema">67177315060904007f00070301040253083230313930393039</AuthenticatedAuxiliaryData>
               <TransactionInfo xmlns="urn:iso:std:iso-iec:24727:tech:schema"/>
             </ns2:AuthenticationProtocolData>
           </ns2:DIDAuthenticate>
         </soap:Body>
       </soap:Envelope>
 -09-09 14:27:28,745+02 [PAOS] DEBUG o.o.common.util.HandlerUtils:125 - Found ConnectionHandle in object of type DIDAuthenticate.
 -09-09 14:27:28,758+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,759+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,759+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,759+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDAuthenticate
 -09-09 14:27:28,761+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:71 - Requesting entry (filterAppId=true) for handle:
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
         SlotHandle: 0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5
         CardType: http://bsi.bund.de/cif/npa.xml
 -09-09 14:27:28,761+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:73 - Current state entries are:
 -09-09 14:27:28,761+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:75 - CardStateEntry@0 {
         handle=  ConnectionHandle {
           ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
           ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00
           idx=0
           slot=null
           app=3F00
           Recognition {
             typ=http://bsi.bund.de/cif/npa.xml
             ident=null
+          }
+        }
+      }
 -09-09 14:27:28,762+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:75 - CardStateEntry@1 {
         handle=  ConnectionHandle {
           ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
           ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00
           idx=0
           slot=0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5
           app=3F00
           Recognition {
             typ=http://bsi.bund.de/cif/npa.xml
             ident=null
+          }
+        }
+      }
 -09-09 14:27:28,769+02 [PAOS] WARN  org.openecard.common.I18n:152 - Failed to load resource eac for lang en.
 -09-09 14:27:28,769+02 [PAOS] WARN  org.openecard.common.I18n:152 - Failed to load resource eac for lang en_US.
 -09-09 14:27:28,785+02 [PAOS] DEBUG o.o.common.util.HandlerUtils:132 - Found ContextHandle in object of type GetIFDCapabilities.
 -09-09 14:27:28,787+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,787+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,787+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,787+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
 -09-09 14:27:28,787+02 [PAOS] WARN  o.o.ifd.scio.wrapper.ChannelManager:87 - Terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00' is already connected.
 -09-09 14:27:28,792+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,792+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,792+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,794+02 [PAOS] WARN  o.o.c.c.a.c.CardVerifiableCertificateChain:60 - Verification of the certificate chain is disabled.
 -09-09 14:27:28,795+02 [PAOS] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of eService against list of communication certificates.
 -09-09 14:27:28,796+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
 -09-09 14:27:28,797+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,797+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
 -09-09 14:27:28,798+02 [PAOS] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://www.fuehrungszeugnis.bund.de against list of communication certificates.
 -09-09 14:27:28,800+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:27:28,800+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,801+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
 -09-09 14:27:28,801+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: C9910FA727FEEBC2A638810E5258A32C53E270B12D42C44C98A64E574B0E95DA
 -09-09 14:27:28,802+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:27:28,802+02 [PAOS] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://eid1.eid-service.de:443 against list of communication certificates.
 -09-09 14:27:28,803+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,804+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,804+02 [PAOS] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://eid1.eid-service.de:443 against list of communication certificates.
 -09-09 14:27:28,805+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,806+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:27:28,806+02 [PAOS] DEBUG o.o.common.util.TR03112Utils:61 - Checking SOP for https://www.fuehrungszeugnis.bund.de/ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/clientInvocation;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02 and https://www.fuehrungszeugnis.bund.de/ffw.
 -09-09 14:27:28,813+02 [PAOS] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Transmit.
 -09-09 14:27:28,816+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:27:28,816+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,816+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,816+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
 -09-09 14:27:28,817+02 [PAOS] DEBUG o.o.i.s.wrapper.SingleThreadChannel:251 - Send APDU: 0022C1A40F800A04007F00070202040202830103
 -09-09 14:27:28,888+02 [PAOS] DEBUG o.o.i.s.wrapper.SingleThreadChannel:254 - Receive APDU: 9000
 -09-09 14:27:28,903+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:27:28,903+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:27:28,903+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:27:28,966+02 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 0, previous was -1.
 -09-09 14:27:28,988+02 [EAC-GUI] WARN  org.openecard.common.I18n:152 - Failed to load resource swing for lang en.
 -09-09 14:27:28,989+02 [EAC-GUI] WARN  org.openecard.common.I18n:152 - Failed to load resource swing for lang en_US.
 -09-09 14:27:29,016+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:65 - Waiting for card to be removed.
 -09-09 14:28:01,888+02 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:244 - Received event: Button.next
 -09-09 14:28:01,896+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:257 - Trying to kill background task if it exists.
 -09-09 14:28:01,896+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:260 - Killing background task.
 -09-09 14:28:01,896+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:222 - Exchange result for step 'Service Provider'.
 -09-09 14:28:01,896+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:76 - Card has not been removed.
 -09-09 14:28:01,897+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:92 - Step PROTOCOL_EAC_GUI_STEP_CVC finished with result OK.
 -09-09 14:28:01,897+02 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:276 - Background task has been terminated from the Swing GUI.
       java.lang.InterruptedException: null
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
       	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
       	at org.openecard.common.util.Promise.deref(Promise.java:140)
       	at org.openecard.common.util.Promise.deref(Promise.java:120)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:66)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:47)
       	at org.openecard.gui.swing.StepFrame$1.run(StepFrame.java:272)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:28:01,903+02 [pool-3-thread-1] WARN  o.o.sal.protocol.eac.gui.CHATStep:131 - Removing community ID verification because of missing AAD.
 -09-09 14:28:01,903+02 [pool-3-thread-1] WARN  o.o.sal.protocol.eac.gui.CHATStep:124 - Removing age verification because of missing or invalid AAD.
 -09-09 14:28:01,906+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:155 - Step Action PROTOCOL_EAC_GUI_STEP_CVC finished with result NEXT.
 -09-09 14:28:01,906+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:186 - Replacing after action with step.id=PROTOCOL_EAC_GUI_STEP_CHAT.
 -09-09 14:28:01,908+02 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 1, previous was 0.
 -09-09 14:28:01,937+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:65 - Waiting for card to be removed.
 -09-09 14:28:06,229+02 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:244 - Received event: Button.next
 -09-09 14:28:06,233+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:257 - Trying to kill background task if it exists.
 -09-09 14:28:06,234+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:260 - Killing background task.
 -09-09 14:28:06,234+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:76 - Card has not been removed.
 -09-09 14:28:06,234+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:222 - Exchange result for step 'Requested information'.
 -09-09 14:28:06,234+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:92 - Step PROTOCOL_EAC_GUI_STEP_CHAT finished with result OK.
 -09-09 14:28:06,234+02 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:276 - Background task has been terminated from the Swing GUI.
       java.lang.InterruptedException: null
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
       	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
       	at org.openecard.common.util.Promise.deref(Promise.java:140)
       	at org.openecard.common.util.Promise.deref(Promise.java:120)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:66)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:47)
       	at org.openecard.gui.swing.StepFrame$1.run(StepFrame.java:272)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:28:06,241+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:155 - Step Action PROTOCOL_EAC_GUI_STEP_CHAT finished with result NEXT.
 -09-09 14:28:06,241+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:186 - Replacing after action with step.id=PROTOCOL_EAC_GUI_STEP_PIN.
 -09-09 14:28:06,242+02 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 2, previous was 1.
 -09-09 14:28:06,270+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:65 - Waiting for card to be removed.
 -09-09 14:28:06,283+02 [Instant-Return-Thread-2] DEBUG o.o.gui.swing.SwingNavigator:244 - Received event: Button.next
 -09-09 14:28:06,288+02 [Instant-Return-Thread-2] DEBUG org.openecard.gui.swing.StepFrame:257 - Trying to kill background task if it exists.
 -09-09 14:28:06,289+02 [Instant-Return-Thread-2] DEBUG org.openecard.gui.swing.StepFrame:260 - Killing background task.
 -09-09 14:28:06,289+02 [Instant-Return-Thread-2] DEBUG org.openecard.gui.swing.StepFrame:222 - Exchange result for step 'Enter the PIN'.
 -09-09 14:28:06,289+02 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:76 - Card has not been removed.
 -09-09 14:28:06,292+02 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:276 - Background task has been terminated from the Swing GUI.
       java.lang.InterruptedException: null
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
       	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
       	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
       	at org.openecard.common.util.Promise.deref(Promise.java:140)
       	at org.openecard.common.util.Promise.deref(Promise.java:120)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:66)
       	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(CardMonitor.java:47)
       	at org.openecard.gui.swing.StepFrame$1.run(StepFrame.java:272)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:28:06,293+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:92 - Step PROTOCOL_EAC_GUI_STEP_PIN finished with result OK.
 -09-09 14:28:06,304+02 [pool-5-thread-1] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type EstablishChannel.
 -09-09 14:28:06,321+02 [pool-5-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:28:06,322+02 [pool-5-thread-1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:06,322+02 [pool-5-thread-1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:06,322+02 [pool-5-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EstablishChannel
 -09-09 14:28:06,332+02 [pool-5-thread-1] DEBUG org.openecard.ifd.scio.IFD:1093 - executeCtrlCode request: 02030303157F4C12060904007F00070301020253050001139B0000E902308202E5060A04007F00070301030101A10E0C0C442D547275737420476D6248A2181316687474703A2F2F7777772E642D74727573742E6E6574A3170C1542756E646573616D742066C3BC72204A757374697AA42A132868747470733A2F2F7777772E6675656872756E67737A6575676E69732E62756E642E64652F666677A58201D80C8201D44E616D652C20416E7363687269667420756E6420452D4D61696C2D4164726573736520646573204469656E737465616E626965746572733A0D0A42756E646573616D742066C3BC72204A757374697A0D0A4164656E61756572616C6C65652039392D3130330D0A353331313320426F6E6E0D0A72656769737465726175736B756E66742D6F6E6C696E654062666A2E62756E642E64650D0A0D0A4765736368C3A46674737A7765636B3A0D0A4F6E6C696E652D416E74726167206175662045727465696C756E6720766F6E2046C3BC6872756E67737A6575676E697373656E20756E6420476577657262657A656E7472616C72656769737465726175736BC3BC6E6674656E0D0A0D0A5A757374C3A46E6469676520446174656E73636875747A626568C3B67264653A0D0A4469652042756E64657362656175667472616774652066C3BC722064656E20446174656E73636875747A20756E642064696520496E666F726D6174696F6E7366726569686569740D0A4875736172656E73747261C39F652033300D0A353331313720426F6E6E0D0A303232382D3939373739392D300D0A706F73747374656C6C6540626664692E62756E642E64650D0A687474703A2F2F7777772E626664692E62756E642E64650D0AA7818B318188042008198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C042087942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F0420C9910FA727FEEBC2A638810E5258A32C53E270B12D42C44C98A64E574B0E95DA0420DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:28:21,877+02 [IFD Watcher 0] DEBUG o.o.ifd.event.IfdEventRunner:262 - Found a card removed event (REINER SCT cyberJack RFID standard (0974243552) 00 00).
 -09-09 14:28:21,877+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.richclient.gui.Status@4afcd809.
 -09-09 14:28:21,877+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:21,878+02 [IFD Watcher 0] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:55 - Received CARD_REMOVED event.
 -09-09 14:28:21,878+02 [IFD Watcher 0] INFO  o.o.s.p.eac.gui.CardRemovedFilter:63 - Card removed during processing of EAC GUI.
 -09-09 14:28:21,878+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.sal.protocol.eac.gui.CardMonitor@1310e5d6.
 -09-09 14:28:21,879+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:378 - Event: CARD_REMOVED
 -09-09 14:28:21,879+02 [IFD Watcher 0] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification CARD_REMOVED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:21,879+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:388 - ConnectionHandle: ifd=REINER SCT cyberJack RFID standard (0974243552) 00 00, slot=null, ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E
 -09-09 14:28:21,879+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:394 - RecognitionInfo: null
 -09-09 14:28:21,879+02 [Dispatcher Event 0] DEBUG org.openecard.richclient.gui.Status:420 - Event sent has responsibility=true for this card.
 -09-09 14:28:21,880+02 [Dispatcher Event 4] INFO  o.o.s.protocol.eac.gui.CardMonitor:84 - Receiving card removed event.
 -09-09 14:28:21,881+02 [Dispatcher Event 2] INFO  o.o.c.sal.state.SALStateCallback:75 - Remove ConnectionHandle from SAL.
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
 -09-09 14:28:21,881+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:114 - Removing CardStateEntries based on connection handle.
       ConnectionHandle:
         Session: YSrUDVv7oNmSYhQhrh1I6A
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         IFDName: REINER SCT cyberJack RFID standard (0974243552) 00 00  SlotIndex: 0
 -09-09 14:28:21,882+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:160 - Internal removeEntry function called with removeSlotHandles=true.
 -09-09 14:28:21,882+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:165 - Removing entry from session map for channel session=YSrUDVv7oNmSYhQhrh1I6A.
 -09-09 14:28:21,882+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
 -09-09 14:28:21,882+02 [IFD Watcher 0] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack RFID standard (0974243552) 00 00' cardPresent=false
 -09-09 14:28:21,882+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:168 - Removing entry from context map for ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E.
 -09-09 14:28:21,882+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:172 - Removing all entries for SlotHandles.
 -09-09 14:28:21,883+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:176 - Removing entry for slot=0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:184 - Removing all protocol instances.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateEntry:172 - Removing 0 protocols from card state entry.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:187 - Removing entry from allEntries set.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:160 - Internal removeEntry function called with removeSlotHandles=true.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:165 - Removing entry from session map for channel session=YSrUDVv7oNmSYhQhrh1I6A.
 -09-09 14:28:21,884+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:168 - Removing entry from context map for ctx=2C6E58C3DA4AA0A1D51B32F6F0E78A0E.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:172 - Removing all entries for SlotHandles.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:176 - Removing entry for slot=0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:184 - Removing all protocol instances.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateEntry:172 - Removing 1 protocols from card state entry.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:146 - Trying to remove protocol org.openecard.addon.sal.SALProtocolProxy@657f8fd8.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:148 - Force removing protocol org.openecard.addon.sal.SALProtocolProxy@657f8fd8.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.sal.protocol.eac.EACProtocol:71 - Destroying EAC protocol instance.
 -09-09 14:28:21,885+02 [Dispatcher Event 2] DEBUG o.o.common.sal.state.CardStateMap:187 - Removing entry from allEntries set.
 -09-09 14:28:21,889+02 [pool-5-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:28:21,889+02 [pool-5-thread-1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:21,889+02 [pool-5-thread-1] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:21,889+02 [pool-5-thread-1] ERROR o.o.s.p.eac.gui.PINStepAction:215 - An unknown error occured while trying to verify the PIN.
 -09-09 14:28:21,890+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:155 - Step Action PROTOCOL_EAC_GUI_STEP_PIN finished with result REPEAT.
 -09-09 14:28:21,891+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:186 - Replacing after action with step.id=PROTOCOL_EAC_GUI_STEP_ERROR.
 -09-09 14:28:21,891+02 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 2, previous was 2.
 -09-09 14:28:25,428+02 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:244 - Received event: Button.next
 -09-09 14:28:25,431+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:257 - Trying to kill background task if it exists.
 -09-09 14:28:25,432+02 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:222 - Exchange result for step 'Error'.
 -09-09 14:28:25,432+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:92 - Step PROTOCOL_EAC_GUI_STEP_ERROR finished with result OK.
 -09-09 14:28:25,432+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:155 - Step Action PROTOCOL_EAC_GUI_STEP_ERROR finished with result CANCEL.
 -09-09 14:28:25,433+02 [EAC-GUI] INFO  o.o.gui.executor.ExecutionEngine:179 - StepAction was canceled.
 -09-09 14:28:25,433+02 [PAOS] DEBUG o.o.sal.protocol.eac.PACEStep:327 - Received error object from GUI.
       org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.common.ECardException.makeException(ECardException.java:64)
       	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47)
       	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69)
       	at org.openecard.sal.protocol.eac.gui.PINStepAction.perform(PINStepAction.java:191)
       	at org.openecard.gui.executor.StepActionCallable.call(StepActionCallable.java:58)
       	at org.openecard.gui.executor.StepActionCallable.call(StepActionCallable.java:37)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
       	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:28:25,433+02 [EAC-GUI] DEBUG o.o.gui.executor.ExecutionEngine:229 - Closing UserConsentNavigator.
 -09-09 14:28:25,433+02 [PAOS] DEBUG o.o.sal.protocol.eac.EACProtocol:100 - Checking if EAC protocol is finished.
 -09-09 14:28:25,433+02 [PAOS] DEBUG o.o.sal.protocol.eac.EACProtocol:120 - EAC authentication finished=false.
 -09-09 14:28:25,436+02 [EAC-GUI] DEBUG o.o.sal.protocol.eac.PACEStep:283 - EAC GUI returned with CANCEL or INTERRUPTED.
 -09-09 14:28:25,472+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:28:25,472+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:25,473+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:25,475+02 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
         POST /ecardpaos/paosreceiver HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: eid2.eid-service.de:443
         PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit";"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework";"urn:iso:std:iso-iec:24727:tech:schema:StartSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe";"urn:iso:std:iso-iec:24727:tech:schema:DataSetList";"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect";"urn:iso:std:iso-iec:24727:tech:schema:DSIList";"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite";"urn:iso:std:iso-iec:24727:tech:schema:DSIRead";"urn:iso:std:iso-iec:24727:tech:schema:Encipher";"urn:iso:std:iso-iec:24727:tech:schema:Decipher";"urn:iso:std:iso-iec:24727:tech:schema:GetRandom";"urn:iso:std:iso-iec:24727:tech:schema:Sign";"urn:iso:std:iso-iec:24727:tech:schema:VerifySignature";"urn:iso:std:iso-iec:24727:tech:schema:VerifyCertificate";"urn:iso:std:iso-iec:24727:tech:schema:DIDList";"urn:iso:std:iso-iec:24727:tech:schema:DIDGet";"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate";"urn:iso:std:iso-iec:24727:tech:schema:ACLList";"urn:iso:std:iso-iec:24727:tech:schema:Hash"
         Accept: text/xml, application/xml, application/vnd.paos+xml
 -09-09 14:28:25,490+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:268 - Message sent:
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
         <Header>
           <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
             <Version>urn:liberty:paos:2006-08</Version>
             <EndpointReference>
               <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
               <MetaData>
                 <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
               </MetaData>
             </EndpointReference>
           </PAOS>
           <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
             <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
           </ReplyTo>
           <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:9cd0f519-5113-4c90-b79a-19c7edd6ecfe</RelatesTo>
           <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:baf94ce0-6c35-47b9-abb1-65c1874cd75b</MessageID>
         </Header>
         <Body>
           <ns3:DIDAuthenticateResponse xmlns="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns10="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns11="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns12="http://www.w3.org/2001/04/xmlenc#" xmlns:ns13="http://ws.openecard.org/schema" xmlns:ns14="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns15="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.w3.org/2000/09/xmldsig#" xmlns:ns3="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns4="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns5="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns6="http://www.w3.org/2009/xmlenc11#" xmlns:ns7="http://www.setcce.org/schemas/ers" xmlns:ns8="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#" xmlns:ns9="http://uri.etsi.org/01903/v1.3.2#" Profile="http://www.bsi.bund.de/ecard/api/1.1">
             <Result>
               <ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ResultMajor>
               <ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ResultMinor>
               <ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.</ResultMessage>
             </Result>
             <ns3:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="ns3:EmptyResponseDataType"/>
           </ns3:DIDAuthenticateResponse>
         </Body>
       </Envelope>
 -09-09 14:28:25,490+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:363 - Sending HTTP request.
 -09-09 14:28:25,695+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:365 - HTTP response received.
 -09-09 14:28:25,696+02 [PAOS] WARN  org.openecard.transport.paos.PAOS:495 - The PAOS endpoint sent the http status code 200 which does not conform to the PAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
 -09-09 14:28:25,697+02 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 200 OK
         Server: JBoss-EAP/7
         Content-Security-Policy: default-src 'self'
         Connection: keep-alive
         Date: Mon, 09 Sep 2019 12:28:25 GMT
         X-Powered-By: Undertow/1
         Content-Type: application/vnd.paos+xml
         Content-Length: 1254
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
          <soap:Header>
             <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id18235" refToMessageID="76285dcf-ab8a-49fb-9ead-dc3640797eb3"/>
             <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:baf94ce0-6c35-47b9-abb1-65c1874cd75b</RelatesTo>
             <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:76036398-7439-48ea-a0b6-11010c58b110</MessageID>
          </soap:Header>
          <soap:Body>
          <ns2:StartPAOSResponse xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="urn:liberty:id-sis-pp:2005-05">
             <ns3:Result>
               <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns3:ResultMajor>
               <ns3:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns3:ResultMinor>
               <ns3:ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.</ns3:ResultMessage>
             </ns3:Result>
           </ns2:StartPAOSResponse>
         </soap:Body>
       </soap:Envelope>
 -09-09 14:28:25,701+02 [PAOS] DEBUG org.openecard.transport.paos.PAOS:224 - Message received:
       <?xml version="1.0" encoding="UTF-8" standalone="no"?>
       <soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
         <soap:Header>
           <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id18235" refToMessageID="76285dcf-ab8a-49fb-9ead-dc3640797eb3"/>
           <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:baf94ce0-6c35-47b9-abb1-65c1874cd75b</RelatesTo>
           <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:76036398-7439-48ea-a0b6-11010c58b110</MessageID>
         </soap:Header>
         <soap:Body>
           <ns2:StartPAOSResponse xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="urn:liberty:id-sis-pp:2005-05">
             <ns3:Result>
               <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns3:ResultMajor>
               <ns3:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns3:ResultMinor>
               <ns3:ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.</ns3:ResultMessage>
             </ns3:Result>
           </ns2:StartPAOSResponse>
         </soap:Body>
       </soap:Envelope>
 -09-09 14:28:25,701+02 [PAOS] WARN  org.openecard.transport.paos.PAOS:233 - Received message without Profile attribute, adding one for proper validation.
 -09-09 14:28:25,706+02 [PAOS] INFO  o.o.c.tls.ClientCertPSKTlsClient:239 - TLS warning sent.
 -09-09 14:28:25,706+02 [PAOS] INFO  o.o.c.tls.ClientCertPSKTlsClient:241 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:28:25,707+02 [PAOS] DEBUG o.o.common.util.HandlerUtils:125 - Found ConnectionHandle in object of type CardApplicationDisconnect.
 -09-09 14:28:25,710+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:28:25,710+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:25,710+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:25,711+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationDisconnect
 -09-09 14:28:25,711+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:135 - Removing CardStateEntries for slot=0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5.
 -09-09 14:28:25,712+02 [PAOS] DEBUG o.o.common.sal.state.CardStateMap:114 - Removing CardStateEntries based on connection handle.
       ConnectionHandle:
         ContextHandle: 2C6E58C3DA4AA0A1D51B32F6F0E78A0E
         SlotHandle: 0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5
 -09-09 14:28:25,712+02 [PAOS] DEBUG o.o.common.util.HandlerUtils:145 - Found SlotHandle in object of type Disconnect.
 -09-09 14:28:25,715+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:105 - Sending API_CALL_STARTED event.
 -09-09 14:28:25,715+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:25,716+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_STARTED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:25,716+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:114 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
 -09-09 14:28:25,716+02 [PAOS] WARN  org.openecard.ifd.scio.IFD:825 - No card available in the requested terminal.
       org.openecard.ifd.scio.wrapper.NoSuchChannel: No channel for slot '0999ECAAC1A05EA1E67620AB3DDB89CAFB1AE4986B2839E5' available.
       	at org.openecard.ifd.scio.wrapper.ChannelManager.getSlaveChannel(ChannelManager.java:119)
       	at org.openecard.ifd.scio.IFD.disconnect(IFD.java:777)
       	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
       	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
       	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
       	at java.lang.reflect.Method.invoke(Method.java:498)
       	at org.openecard.transport.dispatcher.Service.invoke(Service.java:192)
       	at org.openecard.transport.dispatcher.MessageDispatcher.deliver(MessageDispatcher.java:116)
       	at org.openecard.transport.dispatcher.MessageDispatcher.safeDeliver(MessageDispatcher.java:135)
       	at org.openecard.sal.TinySAL.cardApplicationDisconnect(TinySAL.java:481)
       	at org.openecard.sal.SelectorSAL.cardApplicationDisconnect(SelectorSAL.java:320)
       	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
       	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
       	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
       	at java.lang.reflect.Method.invoke(Method.java:498)
       	at org.openecard.transport.dispatcher.Service.invoke(Service.java:192)
       	at org.openecard.transport.dispatcher.MessageDispatcher.deliver(MessageDispatcher.java:116)
       	at org.openecard.transport.dispatcher.MessageDispatcher.safeDeliver(MessageDispatcher.java:135)
       	at org.openecard.binding.tctoken.TCTokenHandler.disconnectHandle(TCTokenHandler.java:272)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:114)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:53)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.lang.Thread.run(Thread.java:748)
 -09-09 14:28:25,722+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:28:25,723+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:25,723+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:25,729+02 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:122 - Sending API_CALL_FINISHED event.
 -09-09 14:28:25,729+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.addon.EventHandler@b7f23d9.
 -09-09 14:28:25,730+02 [PAOS] DEBUG o.o.c.event.EventDispatcherImpl:140 - Sending event notification API_CALL_FINISHED to EventCallback org.openecard.common.sal.state.SALStateCallback@17695df3.
 -09-09 14:28:25,730+02 [Thread-8] ERROR o.o.binding.tctoken.TCTokenHandler:431 - org.openecard.transport.paos.PAOSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       java.util.concurrent.ExecutionException: org.openecard.transport.paos.PAOSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at java.util.concurrent.FutureTask.report(FutureTask.java:122)
       	at java.util.concurrent.FutureTask.get(FutureTask.java:192)
       	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:425)
       	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:234)
       	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:337)
       	at org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:409)
       	at org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
       	at org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
       	at org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
       	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
       	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:127)
       	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:437)
       	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
       	at org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
       Caused by: org.openecard.transport.paos.PAOSException: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:454)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:112)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:53)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.lang.Thread.run(Thread.java:748)
       Caused by: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.common.ECardException.makeException(ECardException.java:64)
       	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47)
       	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69)
       	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:402)
       	... 4 common frames omitted
 -09-09 14:28:25,731+02 [Thread-8] ERROR o.o.binding.tctoken.TCTokenHandler:350 - org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       org.openecard.transport.paos.PAOSException: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:454)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:112)
       	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:53)
       	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       	at java.lang.Thread.run(Thread.java:748)
       Caused by: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
         ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0974243552) 00 00'.
       	at org.openecard.common.ECardException.makeException(ECardException.java:64)
       	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47)
       	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69)
       	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:402)
       	... 4 common frames omitted
 -09-09 14:28:25,734+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://eid1.eid-service.de:443/gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906
 -09-09 14:28:25,736+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - No proxy found for java. Trying next one.
 -09-09 14:28:25,741+02 [Thread-8] DEBUG o.o.c.t.proxy.ProxySettingsLoader$1:80 - Firefox settings folder is {0}
 -09-09 14:28:25,744+02 [Thread-8] INFO  o.o.c.t.proxy.ProxySettingsLoader$1:83 - Proxy found for firefox
 -09-09 14:28:25,744+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:28:25,749+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:28:25,795+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:28:25,848+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
 -09-09 14:28:25,913+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:28:25,914+02 [Thread-8] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://eid1.eid-service.de:443 against list of communication certificates.
 -09-09 14:28:25,915+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:28:25,915+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:28:25,915+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:61 - Checking SOP for https://eid1.eid-service.de:443/gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906 and https://www.fuehrungszeugnis.bund.de/ffw.
 -09-09 14:28:25,915+02 [Thread-8] ERROR o.o.common.util.TR03112Utils:72 - SOP violated; the hosts do not match.
 -09-09 14:28:25,916+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
         GET /gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906 HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: eid1.eid-service.de:443
         Accept: text/xml, */*;q=0.8
         Accept-Charset: utf-8, *;q=0.8
 -09-09 14:28:25,916+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:267 - Sending HTTP request.
 -09-09 14:28:25,985+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:270 - HTTP response received.
 -09-09 14:28:25,986+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 302 Found
         Server: JBoss-EAP/7
         Cache-Control: no-cache, no-store
         Content-Security-Policy: default-src 'self'
         Connection: keep-alive
         Pragma: no-cache
         Date: Mon, 09 Sep 2019 12:28:25 GMT
         Location: https://www.fuehrungszeugnis.bund.de/ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/serverResponse;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?SAMLResponse=rVdpr6PKEf0r1n0fksi6l31zZibCgG0Mttkx%2FvIEdGP2fTFWfny4c%2Be%2BvEyURVEkBKK7q7rOqVOl7i9%2FeRT5aoRtl1Tl1xfsDX1ZwTKsQFLev77Y1u6VffnLty%2BdX%2BR4vTFgV1dlB1eLUdltPka%2Fvgxtuan8Luk2pV%2FAbtOHG5M%2FqRv8Dd3UbdVXYZW%2FrETY9Unp9983ivu%2B7jYIMk3TWzTAuB3Ke%2FeEw71MurdgKMEbgEgUTUhZ%2B68dbMcc9oitd5pJ7mtde%2FVol2FMVHZqHXmfhu1nbH9OO9i9o0nAV0nYShwhChjB7jiU5AgWpymMFDiaQAVhx7%2BVFYBjXtMYyqKvKP6yksWvL79SNGRJlgCvOIHCV5ImqdcAJeErh2JoFPkEzYbssrT83NKqFiOco3AGkhgFIpJmUY7BfA6EGEFwRLT8YItB1w1QLrveL%2FuvLziKca%2Fo%2B2Nh%2BAZnNzj1xlHc7WXlfGZj4e%2FlB%2Feb78bt74n%2F97z73ULLO9cv3z65hgnA3pbXdz6TEC4Ub0iS%2BIJ8z%2BOPHX7Ltdn7%2FdD99CssfK0cPx%2Fgv999wbgYb%2Fihj8udn%2BQQfAKpNx%2BOf%2FYUVN1r0MIClu9hfZdPkZRV%2B75uIwm8If4qGcbFeEG%2B%2FQj4955%2BCvO0KMC%2Fw298uYJtW7Wrye9WLayrtodgFbVVsYKC3wJekzerd3p%2BKDHokt%2FEB8NlAeLXCYK9YUgLuyHvv0eE%2BDkSVkVRlb8MZVZWUym97%2FHXD5yrvlr1rV92RdKvwqrs2ypfvkXhlx9zMVz1sF08%2BfnqD4YknyVjZQrWKpwD2B79MFsZO1lcvasELBGs%2FrhIicRJgqLwP61QdHn%2B8PYTA59wfxr%2BlI5Uhu1cL8j5T038TzL68lgaw%2BY3Z6Lf%2Bz%2F8vE98lPQPHifirWrvCI6iGIKSyNItlhW%2FLBUA3gsFBwQVBSjHsjRGcBzGhmjIENAnI4xbcL6srLle9PUf%2FUn5u1z6RVm%2Fj2yR%2FAn2cQVWfH6v2qSPi%2F%2FClw87DGdfwyB8FxjoNgqc5TKqfiAE3b%2FwgSIo944PdMn9l58igWBx8oEZQhRjGRIjQ4gTDAYA60eQRKkoClnCx7j%2FC4a281%2BxX6l3AN8JEZI6hu17nv5h4Hv5fttPdtXAx2kdKJFodCXT35uCouLzLMXFGVGmxhufzlVcw6g7r0e%2BSFvWvl8KnxyNtfEwTX8gU3rsUSvtKyOqZiL0D9izd46Yuc9SgKj4bcjGWqTJo0XRzaMtLFioaMda9sNAeZBX%2Fno8i4IoHOgm9klGPNOeVs39s8IGCDsg003fK0gYsQA%2BNHz7zB3xxjeWa6t0RmSZ6XGCAZz5TJlHrr9lJ2n7lAiZuqD8E%2Bo04ggnSt279aBQ16HTjbUylyrDFM%2BzhVhehVqkfFYfznzLlNjotxTeaelhxFmBNlxCG%2FAo4Wm%2BKsJp2CdKbDHqtedGNZxqU72AWNB2p61PNdPACBrZHMvTrjrrX79%2BQX7H%2FQfV%2FzD0kY6PRb%2FV0qKTZezvqvtvEthbyHWAZ3VLVE%2BQ1ZYULhlNjiKyK3XiIZxEHDHACRkYY3tEJG2spep5CaOhUJzKrYsyjPJATpfGID3qLlJiezqxl0cKW4%2BWJbJPnmZl9mMWoHu%2F8kJRKXvleTsIqhhWSnJ%2B3HhXC4aGU88TX7DOQcAYjLsTuQNUTtiJKjm1JldETW35w%2FmYxfqevMju2jGoqXP1tTdTMnqpk%2BlKN62r60KrPBqfRgTDYsntLsXyPt5edjWqwCiVLWT94LdmJIFsdlwY3Y4PYNN93uzkCDgdyxBcKTszjxWRuq6T%2Fhrv8GveHNWxH6bKLC%2BkNwSLnqn8DKdq6qx9Tz8cIR2lo0Idx4yajeuBH4zAC4JbfOcNtmEYIsCp2InIRqv4tWxSom0risSHIFxrpoDJsgT2saqzRgDT6TiNjND0KWYoc9PjNeHGO%2FkRqlaZ9gRX3BX8XvtldL1mcKbJu5bbPIrGlCaOEU8G%2B2mrZRKe7%2Fog1uEYX9aCC%2BpsH5ysMtZD%2B2F2sr8OUy%2BWJDQscJX0z0EydNeCh8UlKdU4iAF6AdFpDfCSpYuwKN3nMKh2as45Y6ZEycNwXZDbPBjHXTH2Cpl3Qt%2BclNzhxMt1vQdbRlVvWAII1TE91EMV3lIFYQ4PRy2vwY2%2BjzqM9Xy6UbO51e8AMVG19kkn3qM0ULfJ0r%2BlmE5iy7VYJIDMvX2GZj7Q9ViiTyI9uwXjnULTdztXaXZz7GTwSO6SaEwwTR3Ny7htI%2BLkdYddJ6an2jAaVSodCw5u6p5bNSRymbVTupIH%2BWAdHEsfNO1uavD67LdDwE80uGk7h7%2FK5S5LAtCfvTVPlbulb002dw65wI2HR3vmQx7e2xxh%2BAfdjMe7ePWccJzTAFwMRvNmL59lZrDnujsyV8236JnHx2wUQy33HvlzewaDROE1h2QiEyIzvK4b0BLXQ3Er5gbm2aQ%2FsXoI9tGhw2XjyJ1mEmKgj%2Bwg317ZnU3t7AMjFql%2FjpGGqDMxf2bREx0VXLmn4szcx1ubm0%2FBlaX74Xg7wMLntOvlkMb3Z3nye2Rvqe1WlJwWuDtU8saQx0hfSlkJqeliaXoC9mBCXnORQy5CLpTvniaxz5sgqLL9nF3T88I6k%2BzGBbfxeKSnkIra8OiE3KAd2CG%2BzNjId6OmGn0srhW5P9iChy7Yl8q1M2te06gQX5nO1KFkPkI60kI5uAglpEQwE2odhVKIojzW98%2Bnph4vxgQ1lggvB8%2BYUwrnz94BX4Pr0oGnytlLcWA43NzmpNYPHiHFsi%2BLHB1gS1etbO%2BgDTOyJkTpZhlRdGoOgYts9w%2FJ9On4TjmsNHhy22Uou79ctbxCczNuxHxOD8nWzE%2FoIzCHgxA8dIm4T7Ry7hMTB0ypYOpDK%2FYRFq6NuJKss29AG%2BxyY%2BwxjAuVMuB87mnfSRZ1YrwMgIxvGYEYO2a8sL50lHzdy%2BtiidQ%2BIU7LbUtfMM6x61mqE%2B6cZ8aVVt9FTDFDGU0urFRRcOi9KJbjEhZXUznSx2PDBlpwbOy4Os75VaP1Q17aKpj39FkgRBdbYreVJ20mXFh15%2By8y2sIckfSDYlLr5IOdTLV6eLUyiWOKt4MMvHB20CbuGq2rK2RHgIRl56OVDOse83m6p6y%2Fq2uKq%2ByW01jOkDVRt6iTbOcHu9cfhl8T0fn3HTRRQWBeFHd6XF5xnywS6Dqb%2BX7gykFAl4NO0D2xDaeREfvBsQHJyrpDtq6VhS%2ByDThOeZnp7Z51kkPyhzwtyZX9r1%2BOPboDq%2FX5IQe9uVxupSi8Ewlfu4tVLY4KqbibbszUBznVIOpOnXLhedwVBhaDJgU065Ofyol67SbZQxKfpjolTvb65yMmXaNEPb%2BcQVlTdGaQmuYneAEtI5OJvWsT1V4y4r7ikjq%2FSBg4KJl8HRE9qkYiMuxDDYSYj6jrgYPXaQyVmtcvBWD2OLto%2BoGQ6RH07R3LVOZT8kj9TWzAsLlcghFI9Sx09m%2BJUtHp2MErVV2OeAwpCN1WpB59SVAb3OemMjzZpgYdwsHJ0NJrS0JcmkeVHpTcXc3kg99pBONtJpMz2yoR7VGSRVAT4yp3E13exPaWL7RWpC3W60p70A4EXUuGP%2FTQePH6ePjwvfP94K%2FX60%2Br7bf%2FgY%3D&RelayState=_29527e415df4680971a9dc13393f9711&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=iLVDJbZNQOSiTWTZpHxnB1jVCosg4iVCG21T4gitMHxclnMNKhsoTjSSgNQovuRyWa%2B%2FrUXMs6Yu61TxXVgleGKtu2yxluqZ00A2jBHfTk6GHxt8IG%2BeCubX66svpu4JdWUewN5L%2Fuudlq%2BT8jsp4ufLK%2BmXKcIkuDdh2iKUXy1J8MN8kg8eVNWP%2FFd5TaJC8yRydnuvwErWBPsVnlDGqq6xKc%2BknRkQbVFKcc9T36cjC6DLOSi0HomAgFpIgeeOI6x2FsIA8YXVR3b1di5uMTk%2Bwvzy8GQIseLd0gh5lP%2BgMXg2krzkCdgGBeSPcNxxux4iDa1bZWxPCLyGA19Lyg%3D%3D
         X-Powered-By: Undertow/1
         Content-Length: 0
 -09-09 14:28:25,987+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:28:25,987+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:28:25,988+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://www.fuehrungszeugnis.bund.de/ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/serverResponse;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?SAMLResponse=rVdpr6PKEf0r1n0fksi6l31zZibCgG0Mttkx%2FvIEdGP2fTFWfny4c%2Be%2BvEyURVEkBKK7q7rOqVOl7i9%2FeRT5aoRtl1Tl1xfsDX1ZwTKsQFLev77Y1u6VffnLty%2BdX%2BR4vTFgV1dlB1eLUdltPka%2Fvgxtuan8Luk2pV%2FAbtOHG5M%2FqRv8Dd3UbdVXYZW%2FrETY9Unp9983ivu%2B7jYIMk3TWzTAuB3Ke%2FeEw71MurdgKMEbgEgUTUhZ%2B68dbMcc9oitd5pJ7mtde%2FVol2FMVHZqHXmfhu1nbH9OO9i9o0nAV0nYShwhChjB7jiU5AgWpymMFDiaQAVhx7%2BVFYBjXtMYyqKvKP6yksWvL79SNGRJlgCvOIHCV5ImqdcAJeErh2JoFPkEzYbssrT83NKqFiOco3AGkhgFIpJmUY7BfA6EGEFwRLT8YItB1w1QLrveL%2FuvLziKca%2Fo%2B2Nh%2BAZnNzj1xlHc7WXlfGZj4e%2FlB%2Feb78bt74n%2F97z73ULLO9cv3z65hgnA3pbXdz6TEC4Ub0iS%2BIJ8z%2BOPHX7Ltdn7%2FdD99CssfK0cPx%2Fgv999wbgYb%2Fihj8udn%2BQQfAKpNx%2BOf%2FYUVN1r0MIClu9hfZdPkZRV%2B75uIwm8If4qGcbFeEG%2B%2FQj4955%2BCvO0KMC%2Fw298uYJtW7Wrye9WLayrtodgFbVVsYKC3wJekzerd3p%2BKDHokt%2FEB8NlAeLXCYK9YUgLuyHvv0eE%2BDkSVkVRlb8MZVZWUym97%2FHXD5yrvlr1rV92RdKvwqrs2ypfvkXhlx9zMVz1sF08%2BfnqD4YknyVjZQrWKpwD2B79MFsZO1lcvasELBGs%2FrhIicRJgqLwP61QdHn%2B8PYTA59wfxr%2BlI5Uhu1cL8j5T038TzL68lgaw%2BY3Z6Lf%2Bz%2F8vE98lPQPHifirWrvCI6iGIKSyNItlhW%2FLBUA3gsFBwQVBSjHsjRGcBzGhmjIENAnI4xbcL6srLle9PUf%2FUn5u1z6RVm%2Fj2yR%2FAn2cQVWfH6v2qSPi%2F%2FClw87DGdfwyB8FxjoNgqc5TKqfiAE3b%2FwgSIo944PdMn9l58igWBx8oEZQhRjGRIjQ4gTDAYA60eQRKkoClnCx7j%2FC4a281%2BxX6l3AN8JEZI6hu17nv5h4Hv5fttPdtXAx2kdKJFodCXT35uCouLzLMXFGVGmxhufzlVcw6g7r0e%2BSFvWvl8KnxyNtfEwTX8gU3rsUSvtKyOqZiL0D9izd46Yuc9SgKj4bcjGWqTJo0XRzaMtLFioaMda9sNAeZBX%2Fno8i4IoHOgm9klGPNOeVs39s8IGCDsg003fK0gYsQA%2BNHz7zB3xxjeWa6t0RmSZ6XGCAZz5TJlHrr9lJ2n7lAiZuqD8E%2Bo04ggnSt279aBQ16HTjbUylyrDFM%2BzhVhehVqkfFYfznzLlNjotxTeaelhxFmBNlxCG%2FAo4Wm%2BKsJp2CdKbDHqtedGNZxqU72AWNB2p61PNdPACBrZHMvTrjrrX79%2BQX7H%2FQfV%2FzD0kY6PRb%2FV0qKTZezvqvtvEthbyHWAZ3VLVE%2BQ1ZYULhlNjiKyK3XiIZxEHDHACRkYY3tEJG2spep5CaOhUJzKrYsyjPJATpfGID3qLlJiezqxl0cKW4%2BWJbJPnmZl9mMWoHu%2F8kJRKXvleTsIqhhWSnJ%2B3HhXC4aGU88TX7DOQcAYjLsTuQNUTtiJKjm1JldETW35w%2FmYxfqevMju2jGoqXP1tTdTMnqpk%2BlKN62r60KrPBqfRgTDYsntLsXyPt5edjWqwCiVLWT94LdmJIFsdlwY3Y4PYNN93uzkCDgdyxBcKTszjxWRuq6T%2Fhrv8GveHNWxH6bKLC%2BkNwSLnqn8DKdq6qx9Tz8cIR2lo0Idx4yajeuBH4zAC4JbfOcNtmEYIsCp2InIRqv4tWxSom0risSHIFxrpoDJsgT2saqzRgDT6TiNjND0KWYoc9PjNeHGO%2FkRqlaZ9gRX3BX8XvtldL1mcKbJu5bbPIrGlCaOEU8G%2B2mrZRKe7%2Fog1uEYX9aCC%2BpsH5ysMtZD%2B2F2sr8OUy%2BWJDQscJX0z0EydNeCh8UlKdU4iAF6AdFpDfCSpYuwKN3nMKh2as45Y6ZEycNwXZDbPBjHXTH2Cpl3Qt%2BclNzhxMt1vQdbRlVvWAII1TE91EMV3lIFYQ4PRy2vwY2%2BjzqM9Xy6UbO51e8AMVG19kkn3qM0ULfJ0r%2BlmE5iy7VYJIDMvX2GZj7Q9ViiTyI9uwXjnULTdztXaXZz7GTwSO6SaEwwTR3Ny7htI%2BLkdYddJ6an2jAaVSodCw5u6p5bNSRymbVTupIH%2BWAdHEsfNO1uavD67LdDwE80uGk7h7%2FK5S5LAtCfvTVPlbulb002dw65wI2HR3vmQx7e2xxh%2BAfdjMe7ePWccJzTAFwMRvNmL59lZrDnujsyV8236JnHx2wUQy33HvlzewaDROE1h2QiEyIzvK4b0BLXQ3Er5gbm2aQ%2FsXoI9tGhw2XjyJ1mEmKgj%2Bwg317ZnU3t7AMjFql%2FjpGGqDMxf2bREx0VXLmn4szcx1ubm0%2FBlaX74Xg7wMLntOvlkMb3Z3nye2Rvqe1WlJwWuDtU8saQx0hfSlkJqeliaXoC9mBCXnORQy5CLpTvniaxz5sgqLL9nF3T88I6k%2BzGBbfxeKSnkIra8OiE3KAd2CG%2BzNjId6OmGn0srhW5P9iChy7Yl8q1M2te06gQX5nO1KFkPkI60kI5uAglpEQwE2odhVKIojzW98%2Bnph4vxgQ1lggvB8%2BYUwrnz94BX4Pr0oGnytlLcWA43NzmpNYPHiHFsi%2BLHB1gS1etbO%2BgDTOyJkTpZhlRdGoOgYts9w%2FJ9On4TjmsNHhy22Uou79ctbxCczNuxHxOD8nWzE%2FoIzCHgxA8dIm4T7Ry7hMTB0ypYOpDK%2FYRFq6NuJKss29AG%2BxyY%2BwxjAuVMuB87mnfSRZ1YrwMgIxvGYEYO2a8sL50lHzdy%2BtiidQ%2BIU7LbUtfMM6x61mqE%2B6cZ8aVVt9FTDFDGU0urFRRcOi9KJbjEhZXUznSx2PDBlpwbOy4Os75VaP1Q17aKpj39FkgRBdbYreVJ20mXFh15%2By8y2sIckfSDYlLr5IOdTLV6eLUyiWOKt4MMvHB20CbuGq2rK2RHgIRl56OVDOse83m6p6y%2Fq2uKq%2ByW01jOkDVRt6iTbOcHu9cfhl8T0fn3HTRRQWBeFHd6XF5xnywS6Dqb%2BX7gykFAl4NO0D2xDaeREfvBsQHJyrpDtq6VhS%2ByDThOeZnp7Z51kkPyhzwtyZX9r1%2BOPboDq%2FX5IQe9uVxupSi8Ewlfu4tVLY4KqbibbszUBznVIOpOnXLhedwVBhaDJgU065Ofyol67SbZQxKfpjolTvb65yMmXaNEPb%2BcQVlTdGaQmuYneAEtI5OJvWsT1V4y4r7ikjq%2FSBg4KJl8HRE9qkYiMuxDDYSYj6jrgYPXaQyVmtcvBWD2OLto%2BoGQ6RH07R3LVOZT8kj9TWzAsLlcghFI9Sx09m%2BJUtHp2MErVV2OeAwpCN1WpB59SVAb3OemMjzZpgYdwsHJ0NJrS0JcmkeVHpTcXc3kg99pBONtJpMz2yoR7VGSRVAT4yp3E13exPaWL7RWpC3W60p70A4EXUuGP%2FTQePH6ePjwvfP94K%2FX60%2Br7bf%2FgY%3D&RelayState=_29527e415df4680971a9dc13393f9711&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=iLVDJbZNQOSiTWTZpHxnB1jVCosg4iVCG21T4gitMHxclnMNKhsoTjSSgNQovuRyWa%2B%2FrUXMs6Yu61TxXVgleGKtu2yxluqZ00A2jBHfTk6GHxt8IG%2BeCubX66svpu4JdWUewN5L%2Fuudlq%2BT8jsp4ufLK%2BmXKcIkuDdh2iKUXy1J8MN8kg8eVNWP%2FFd5TaJC8yRydnuvwErWBPsVnlDGqq6xKc%2BknRkQbVFKcc9T36cjC6DLOSi0HomAgFpIgeeOI6x2FsIA8YXVR3b1di5uMTk%2Bwvzy8GQIseLd0gh5lP%2BgMXg2krzkCdgGBeSPcNxxux4iDa1bZWxPCLyGA19Lyg%3D%3D
 -09-09 14:28:25,988+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:28:25,989+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:28:26,011+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:28:26,041+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [www.fuehrungszeugnis.bund.de] [www.fuehrungszeugnis.bund.de]
 -09-09 14:28:26,116+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:28:26,116+02 [Thread-8] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://www.fuehrungszeugnis.bund.de against list of communication certificates.
 -09-09 14:28:26,117+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:28:26,118+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:28:26,118+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
 -09-09 14:28:26,119+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: C9910FA727FEEBC2A638810E5258A32C53E270B12D42C44C98A64E574B0E95DA
 -09-09 14:28:26,119+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:28:26,119+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:61 - Checking SOP for https://eid1.eid-service.de:443/gov_autent/async?refID=_4d96904946c5e9b17ab118357cd20acecbc27906 and https://www.fuehrungszeugnis.bund.de/ffw.
 -09-09 14:28:26,120+02 [Thread-8] ERROR o.o.common.util.TR03112Utils:72 - SOP violated; the hosts do not match.
 -09-09 14:28:26,120+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
         GET /ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/serverResponse;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?SAMLResponse=rVdpr6PKEf0r1n0fksi6l31zZibCgG0Mttkx%2FvIEdGP2fTFWfny4c%2Be%2BvEyURVEkBKK7q7rOqVOl7i9%2FeRT5aoRtl1Tl1xfsDX1ZwTKsQFLev77Y1u6VffnLty%2BdX%2BR4vTFgV1dlB1eLUdltPka%2Fvgxtuan8Luk2pV%2FAbtOHG5M%2FqRv8Dd3UbdVXYZW%2FrETY9Unp9983ivu%2B7jYIMk3TWzTAuB3Ke%2FeEw71MurdgKMEbgEgUTUhZ%2B68dbMcc9oitd5pJ7mtde%2FVol2FMVHZqHXmfhu1nbH9OO9i9o0nAV0nYShwhChjB7jiU5AgWpymMFDiaQAVhx7%2BVFYBjXtMYyqKvKP6yksWvL79SNGRJlgCvOIHCV5ImqdcAJeErh2JoFPkEzYbssrT83NKqFiOco3AGkhgFIpJmUY7BfA6EGEFwRLT8YItB1w1QLrveL%2FuvLziKca%2Fo%2B2Nh%2BAZnNzj1xlHc7WXlfGZj4e%2FlB%2Feb78bt74n%2F97z73ULLO9cv3z65hgnA3pbXdz6TEC4Ub0iS%2BIJ8z%2BOPHX7Ltdn7%2FdD99CssfK0cPx%2Fgv999wbgYb%2Fihj8udn%2BQQfAKpNx%2BOf%2FYUVN1r0MIClu9hfZdPkZRV%2B75uIwm8If4qGcbFeEG%2B%2FQj4955%2BCvO0KMC%2Fw298uYJtW7Wrye9WLayrtodgFbVVsYKC3wJekzerd3p%2BKDHokt%2FEB8NlAeLXCYK9YUgLuyHvv0eE%2BDkSVkVRlb8MZVZWUym97%2FHXD5yrvlr1rV92RdKvwqrs2ypfvkXhlx9zMVz1sF08%2BfnqD4YknyVjZQrWKpwD2B79MFsZO1lcvasELBGs%2FrhIicRJgqLwP61QdHn%2B8PYTA59wfxr%2BlI5Uhu1cL8j5T038TzL68lgaw%2BY3Z6Lf%2Bz%2F8vE98lPQPHifirWrvCI6iGIKSyNItlhW%2FLBUA3gsFBwQVBSjHsjRGcBzGhmjIENAnI4xbcL6srLle9PUf%2FUn5u1z6RVm%2Fj2yR%2FAn2cQVWfH6v2qSPi%2F%2FClw87DGdfwyB8FxjoNgqc5TKqfiAE3b%2FwgSIo944PdMn9l58igWBx8oEZQhRjGRIjQ4gTDAYA60eQRKkoClnCx7j%2FC4a281%2BxX6l3AN8JEZI6hu17nv5h4Hv5fttPdtXAx2kdKJFodCXT35uCouLzLMXFGVGmxhufzlVcw6g7r0e%2BSFvWvl8KnxyNtfEwTX8gU3rsUSvtKyOqZiL0D9izd46Yuc9SgKj4bcjGWqTJo0XRzaMtLFioaMda9sNAeZBX%2Fno8i4IoHOgm9klGPNOeVs39s8IGCDsg003fK0gYsQA%2BNHz7zB3xxjeWa6t0RmSZ6XGCAZz5TJlHrr9lJ2n7lAiZuqD8E%2Bo04ggnSt279aBQ16HTjbUylyrDFM%2BzhVhehVqkfFYfznzLlNjotxTeaelhxFmBNlxCG%2FAo4Wm%2BKsJp2CdKbDHqtedGNZxqU72AWNB2p61PNdPACBrZHMvTrjrrX79%2BQX7H%2FQfV%2FzD0kY6PRb%2FV0qKTZezvqvtvEthbyHWAZ3VLVE%2BQ1ZYULhlNjiKyK3XiIZxEHDHACRkYY3tEJG2spep5CaOhUJzKrYsyjPJATpfGID3qLlJiezqxl0cKW4%2BWJbJPnmZl9mMWoHu%2F8kJRKXvleTsIqhhWSnJ%2B3HhXC4aGU88TX7DOQcAYjLsTuQNUTtiJKjm1JldETW35w%2FmYxfqevMju2jGoqXP1tTdTMnqpk%2BlKN62r60KrPBqfRgTDYsntLsXyPt5edjWqwCiVLWT94LdmJIFsdlwY3Y4PYNN93uzkCDgdyxBcKTszjxWRuq6T%2Fhrv8GveHNWxH6bKLC%2BkNwSLnqn8DKdq6qx9Tz8cIR2lo0Idx4yajeuBH4zAC4JbfOcNtmEYIsCp2InIRqv4tWxSom0risSHIFxrpoDJsgT2saqzRgDT6TiNjND0KWYoc9PjNeHGO%2FkRqlaZ9gRX3BX8XvtldL1mcKbJu5bbPIrGlCaOEU8G%2B2mrZRKe7%2Fog1uEYX9aCC%2BpsH5ysMtZD%2B2F2sr8OUy%2BWJDQscJX0z0EydNeCh8UlKdU4iAF6AdFpDfCSpYuwKN3nMKh2as45Y6ZEycNwXZDbPBjHXTH2Cpl3Qt%2BclNzhxMt1vQdbRlVvWAII1TE91EMV3lIFYQ4PRy2vwY2%2BjzqM9Xy6UbO51e8AMVG19kkn3qM0ULfJ0r%2BlmE5iy7VYJIDMvX2GZj7Q9ViiTyI9uwXjnULTdztXaXZz7GTwSO6SaEwwTR3Ny7htI%2BLkdYddJ6an2jAaVSodCw5u6p5bNSRymbVTupIH%2BWAdHEsfNO1uavD67LdDwE80uGk7h7%2FK5S5LAtCfvTVPlbulb002dw65wI2HR3vmQx7e2xxh%2BAfdjMe7ePWccJzTAFwMRvNmL59lZrDnujsyV8236JnHx2wUQy33HvlzewaDROE1h2QiEyIzvK4b0BLXQ3Er5gbm2aQ%2FsXoI9tGhw2XjyJ1mEmKgj%2Bwg317ZnU3t7AMjFql%2FjpGGqDMxf2bREx0VXLmn4szcx1ubm0%2FBlaX74Xg7wMLntOvlkMb3Z3nye2Rvqe1WlJwWuDtU8saQx0hfSlkJqeliaXoC9mBCXnORQy5CLpTvniaxz5sgqLL9nF3T88I6k%2BzGBbfxeKSnkIra8OiE3KAd2CG%2BzNjId6OmGn0srhW5P9iChy7Yl8q1M2te06gQX5nO1KFkPkI60kI5uAglpEQwE2odhVKIojzW98%2Bnph4vxgQ1lggvB8%2BYUwrnz94BX4Pr0oGnytlLcWA43NzmpNYPHiHFsi%2BLHB1gS1etbO%2BgDTOyJkTpZhlRdGoOgYts9w%2FJ9On4TjmsNHhy22Uou79ctbxCczNuxHxOD8nWzE%2FoIzCHgxA8dIm4T7Ry7hMTB0ypYOpDK%2FYRFq6NuJKss29AG%2BxyY%2BwxjAuVMuB87mnfSRZ1YrwMgIxvGYEYO2a8sL50lHzdy%2BtiidQ%2BIU7LbUtfMM6x61mqE%2B6cZ8aVVt9FTDFDGU0urFRRcOi9KJbjEhZXUznSx2PDBlpwbOy4Os75VaP1Q17aKpj39FkgRBdbYreVJ20mXFh15%2By8y2sIckfSDYlLr5IOdTLV6eLUyiWOKt4MMvHB20CbuGq2rK2RHgIRl56OVDOse83m6p6y%2Fq2uKq%2ByW01jOkDVRt6iTbOcHu9cfhl8T0fn3HTRRQWBeFHd6XF5xnywS6Dqb%2BX7gykFAl4NO0D2xDaeREfvBsQHJyrpDtq6VhS%2ByDThOeZnp7Z51kkPyhzwtyZX9r1%2BOPboDq%2FX5IQe9uVxupSi8Ewlfu4tVLY4KqbibbszUBznVIOpOnXLhedwVBhaDJgU065Ofyol67SbZQxKfpjolTvb65yMmXaNEPb%2BcQVlTdGaQmuYneAEtI5OJvWsT1V4y4r7ikjq%2FSBg4KJl8HRE9qkYiMuxDDYSYj6jrgYPXaQyVmtcvBWD2OLto%2BoGQ6RH07R3LVOZT8kj9TWzAsLlcghFI9Sx09m%2BJUtHp2MErVV2OeAwpCN1WpB59SVAb3OemMjzZpgYdwsHJ0NJrS0JcmkeVHpTcXc3kg99pBONtJpMz2yoR7VGSRVAT4yp3E13exPaWL7RWpC3W60p70A4EXUuGP%2FTQePH6ePjwvfP94K%2FX60%2Br7bf%2FgY%3D&RelayState=_29527e415df4680971a9dc13393f9711&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=iLVDJbZNQOSiTWTZpHxnB1jVCosg4iVCG21T4gitMHxclnMNKhsoTjSSgNQovuRyWa%2B%2FrUXMs6Yu61TxXVgleGKtu2yxluqZ00A2jBHfTk6GHxt8IG%2BeCubX66svpu4JdWUewN5L%2Fuudlq%2BT8jsp4ufLK%2BmXKcIkuDdh2iKUXy1J8MN8kg8eVNWP%2FFd5TaJC8yRydnuvwErWBPsVnlDGqq6xKc%2BknRkQbVFKcc9T36cjC6DLOSi0HomAgFpIgeeOI6x2FsIA8YXVR3b1di5uMTk%2Bwvzy8GQIseLd0gh5lP%2BgMXg2krzkCdgGBeSPcNxxux4iDa1bZWxPCLyGA19Lyg%3D%3D HTTP/1.1
         Connection: keep-alive
         User-Agent: Open eCard App/1.3.0
         Host: www.fuehrungszeugnis.bund.de
         Accept: text/xml, */*;q=0.8
         Accept-Charset: utf-8, *;q=0.8
 -09-09 14:28:26,120+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:267 - Sending HTTP request.
 -09-09 14:28:26,161+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:270 - HTTP response received.
 -09-09 14:28:26,162+02 [Thread-8] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
         HTTP/1.1 302 Found
         Date: Mon, 09 Sep 2019 12:28:26 GMT
         Server: Apache
         Content-Length: 0
         Strict-Transport-Security: max-age=63072000;
         Cache-Control: no-cache, no-store
         Strict-Transport-Security: max-age=31536000
         Location: https://www.fuehrungszeugnis.bund.de/ffw/form/display.do;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?%24context=0EE2D2329C2215AF85BA
         X-Frame-Options: SAMEORIGIN
         Keep-Alive: timeout=10, max=500
         Connection: Keep-Alive
         Content-Type: text/plain
 -09-09 14:28:26,163+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:28:26,163+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:28:26,164+02 [Thread-8] INFO  o.o.binding.tctoken.ResourceContext:207 - Trying to load resource from: https://www.fuehrungszeugnis.bund.de/ffw/form/display.do;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?%24context=0EE2D2329C2215AF85BA
 -09-09 14:28:26,164+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
 -09-09 14:28:26,165+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
 -09-09 14:28:26,189+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:244 - Performing TLS handshake.
 -09-09 14:28:26,219+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [www.fuehrungszeugnis.bund.de] [www.fuehrungszeugnis.bund.de]
 -09-09 14:28:26,291+02 [Thread-8] DEBUG o.o.binding.tctoken.ResourceContext:246 - TLS handshake performed.
 -09-09 14:28:26,292+02 [Thread-8] INFO  o.o.common.util.TR03112Utils:107 - Checking certificate hash of https://www.fuehrungszeugnis.bund.de against list of communication certificates.
 -09-09 14:28:26,294+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:115 - Hash of the retrieved server certificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:28:26,295+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
 -09-09 14:28:26,296+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
 -09-09 14:28:26,298+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: C9910FA727FEEBC2A638810E5258A32C53E270B12D42C44C98A64E574B0E95DA
 -09-09 14:28:26,299+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:120 - CommCertificate: DEB9F8982832998110E24CC83C7D9ABEB37AE9661CCB3060035E698FC8737D94
 -09-09 14:28:26,299+02 [Thread-8] DEBUG o.o.common.util.TR03112Utils:61 - Checking SOP for https://www.fuehrungszeugnis.bund.de/ffw/npa-servlet/UQsPS4GpQP-Y6W77S0IVpQ/serverResponse;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?SAMLResponse=rVdpr6PKEf0r1n0fksi6l31zZibCgG0Mttkx%2FvIEdGP2fTFWfny4c%2Be%2BvEyURVEkBKK7q7rOqVOl7i9%2FeRT5aoRtl1Tl1xfsDX1ZwTKsQFLev77Y1u6VffnLty%2BdX%2BR4vTFgV1dlB1eLUdltPka%2Fvgxtuan8Luk2pV%2FAbtOHG5M%2FqRv8Dd3UbdVXYZW%2FrETY9Unp9983ivu%2B7jYIMk3TWzTAuB3Ke%2FeEw71MurdgKMEbgEgUTUhZ%2B68dbMcc9oitd5pJ7mtde%2FVol2FMVHZqHXmfhu1nbH9OO9i9o0nAV0nYShwhChjB7jiU5AgWpymMFDiaQAVhx7%2BVFYBjXtMYyqKvKP6yksWvL79SNGRJlgCvOIHCV5ImqdcAJeErh2JoFPkEzYbssrT83NKqFiOco3AGkhgFIpJmUY7BfA6EGEFwRLT8YItB1w1QLrveL%2FuvLziKca%2Fo%2B2Nh%2BAZnNzj1xlHc7WXlfGZj4e%2FlB%2Feb78bt74n%2F97z73ULLO9cv3z65hgnA3pbXdz6TEC4Ub0iS%2BIJ8z%2BOPHX7Ltdn7%2FdD99CssfK0cPx%2Fgv999wbgYb%2Fihj8udn%2BQQfAKpNx%2BOf%2FYUVN1r0MIClu9hfZdPkZRV%2B75uIwm8If4qGcbFeEG%2B%2FQj4955%2BCvO0KMC%2Fw298uYJtW7Wrye9WLayrtodgFbVVsYKC3wJekzerd3p%2BKDHokt%2FEB8NlAeLXCYK9YUgLuyHvv0eE%2BDkSVkVRlb8MZVZWUym97%2FHXD5yrvlr1rV92RdKvwqrs2ypfvkXhlx9zMVz1sF08%2BfnqD4YknyVjZQrWKpwD2B79MFsZO1lcvasELBGs%2FrhIicRJgqLwP61QdHn%2B8PYTA59wfxr%2BlI5Uhu1cL8j5T038TzL68lgaw%2BY3Z6Lf%2Bz%2F8vE98lPQPHifirWrvCI6iGIKSyNItlhW%2FLBUA3gsFBwQVBSjHsjRGcBzGhmjIENAnI4xbcL6srLle9PUf%2FUn5u1z6RVm%2Fj2yR%2FAn2cQVWfH6v2qSPi%2F%2FClw87DGdfwyB8FxjoNgqc5TKqfiAE3b%2FwgSIo944PdMn9l58igWBx8oEZQhRjGRIjQ4gTDAYA60eQRKkoClnCx7j%2FC4a281%2BxX6l3AN8JEZI6hu17nv5h4Hv5fttPdtXAx2kdKJFodCXT35uCouLzLMXFGVGmxhufzlVcw6g7r0e%2BSFvWvl8KnxyNtfEwTX8gU3rsUSvtKyOqZiL0D9izd46Yuc9SgKj4bcjGWqTJo0XRzaMtLFioaMda9sNAeZBX%2Fno8i4IoHOgm9klGPNOeVs39s8IGCDsg003fK0gYsQA%2BNHz7zB3xxjeWa6t0RmSZ6XGCAZz5TJlHrr9lJ2n7lAiZuqD8E%2Bo04ggnSt279aBQ16HTjbUylyrDFM%2BzhVhehVqkfFYfznzLlNjotxTeaelhxFmBNlxCG%2FAo4Wm%2BKsJp2CdKbDHqtedGNZxqU72AWNB2p61PNdPACBrZHMvTrjrrX79%2BQX7H%2FQfV%2FzD0kY6PRb%2FV0qKTZezvqvtvEthbyHWAZ3VLVE%2BQ1ZYULhlNjiKyK3XiIZxEHDHACRkYY3tEJG2spep5CaOhUJzKrYsyjPJATpfGID3qLlJiezqxl0cKW4%2BWJbJPnmZl9mMWoHu%2F8kJRKXvleTsIqhhWSnJ%2B3HhXC4aGU88TX7DOQcAYjLsTuQNUTtiJKjm1JldETW35w%2FmYxfqevMju2jGoqXP1tTdTMnqpk%2BlKN62r60KrPBqfRgTDYsntLsXyPt5edjWqwCiVLWT94LdmJIFsdlwY3Y4PYNN93uzkCDgdyxBcKTszjxWRuq6T%2Fhrv8GveHNWxH6bKLC%2BkNwSLnqn8DKdq6qx9Tz8cIR2lo0Idx4yajeuBH4zAC4JbfOcNtmEYIsCp2InIRqv4tWxSom0risSHIFxrpoDJsgT2saqzRgDT6TiNjND0KWYoc9PjNeHGO%2FkRqlaZ9gRX3BX8XvtldL1mcKbJu5bbPIrGlCaOEU8G%2B2mrZRKe7%2Fog1uEYX9aCC%2BpsH5ysMtZD%2B2F2sr8OUy%2BWJDQscJX0z0EydNeCh8UlKdU4iAF6AdFpDfCSpYuwKN3nMKh2as45Y6ZEycNwXZDbPBjHXTH2Cpl3Qt%2BclNzhxMt1vQdbRlVvWAII1TE91EMV3lIFYQ4PRy2vwY2%2BjzqM9Xy6UbO51e8AMVG19kkn3qM0ULfJ0r%2BlmE5iy7VYJIDMvX2GZj7Q9ViiTyI9uwXjnULTdztXaXZz7GTwSO6SaEwwTR3Ny7htI%2BLkdYddJ6an2jAaVSodCw5u6p5bNSRymbVTupIH%2BWAdHEsfNO1uavD67LdDwE80uGk7h7%2FK5S5LAtCfvTVPlbulb002dw65wI2HR3vmQx7e2xxh%2BAfdjMe7ePWccJzTAFwMRvNmL59lZrDnujsyV8236JnHx2wUQy33HvlzewaDROE1h2QiEyIzvK4b0BLXQ3Er5gbm2aQ%2FsXoI9tGhw2XjyJ1mEmKgj%2Bwg317ZnU3t7AMjFql%2FjpGGqDMxf2bREx0VXLmn4szcx1ubm0%2FBlaX74Xg7wMLntOvlkMb3Z3nye2Rvqe1WlJwWuDtU8saQx0hfSlkJqeliaXoC9mBCXnORQy5CLpTvniaxz5sgqLL9nF3T88I6k%2BzGBbfxeKSnkIra8OiE3KAd2CG%2BzNjId6OmGn0srhW5P9iChy7Yl8q1M2te06gQX5nO1KFkPkI60kI5uAglpEQwE2odhVKIojzW98%2Bnph4vxgQ1lggvB8%2BYUwrnz94BX4Pr0oGnytlLcWA43NzmpNYPHiHFsi%2BLHB1gS1etbO%2BgDTOyJkTpZhlRdGoOgYts9w%2FJ9On4TjmsNHhy22Uou79ctbxCczNuxHxOD8nWzE%2FoIzCHgxA8dIm4T7Ry7hMTB0ypYOpDK%2FYRFq6NuJKss29AG%2BxyY%2BwxjAuVMuB87mnfSRZ1YrwMgIxvGYEYO2a8sL50lHzdy%2BtiidQ%2BIU7LbUtfMM6x61mqE%2B6cZ8aVVt9FTDFDGU0urFRRcOi9KJbjEhZXUznSx2PDBlpwbOy4Os75VaP1Q17aKpj39FkgRBdbYreVJ20mXFh15%2By8y2sIckfSDYlLr5IOdTLV6eLUyiWOKt4MMvHB20CbuGq2rK2RHgIRl56OVDOse83m6p6y%2Fq2uKq%2ByW01jOkDVRt6iTbOcHu9cfhl8T0fn3HTRRQWBeFHd6XF5xnywS6Dqb%2BX7gykFAl4NO0D2xDaeREfvBsQHJyrpDtq6VhS%2ByDThOeZnp7Z51kkPyhzwtyZX9r1%2BOPboDq%2FX5IQe9uVxupSi8Ewlfu4tVLY4KqbibbszUBznVIOpOnXLhedwVBhaDJgU065Ofyol67SbZQxKfpjolTvb65yMmXaNEPb%2BcQVlTdGaQmuYneAEtI5OJvWsT1V4y4r7ikjq%2FSBg4KJl8HRE9qkYiMuxDDYSYj6jrgYPXaQyVmtcvBWD2OLto%2BoGQ6RH07R3LVOZT8kj9TWzAsLlcghFI9Sx09m%2BJUtHp2MErVV2OeAwpCN1WpB59SVAb3OemMjzZpgYdwsHJ0NJrS0JcmkeVHpTcXc3kg99pBONtJpMz2yoR7VGSRVAT4yp3E13exPaWL7RWpC3W60p70A4EXUuGP%2FTQePH6ePjwvfP94K%2FX60%2Br7bf%2FgY%3D&RelayState=_29527e415df4680971a9dc13393f9711&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=iLVDJbZNQOSiTWTZpHxnB1jVCosg4iVCG21T4gitMHxclnMNKhsoTjSSgNQovuRyWa%2B%2FrUXMs6Yu61TxXVgleGKtu2yxluqZ00A2jBHfTk6GHxt8IG%2BeCubX66svpu4JdWUewN5L%2Fuudlq%2BT8jsp4ufLK%2BmXKcIkuDdh2iKUXy1J8MN8kg8eVNWP%2FFd5TaJC8yRydnuvwErWBPsVnlDGqq6xKc%2BknRkQbVFKcc9T36cjC6DLOSi0HomAgFpIgeeOI6x2FsIA8YXVR3b1di5uMTk%2Bwvzy8GQIseLd0gh5lP%2BgMXg2krzkCdgGBeSPcNxxux4iDa1bZWxPCLyGA19Lyg%3D%3D and https://www.fuehrungszeugnis.bund.de/ffw.
 -09-09 14:28:26,300+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:298 - TLS warning sent.
 -09-09 14:28:26,300+02 [Thread-8] INFO  o.o.c.t.ClientCertDefaultTlsClient:300 - TLS(WARN): Close [close_notify=0] --> Connection closed
 -09-09 14:28:26,300+02 [Thread-8] DEBUG o.o.binding.tctoken.TCTokenHandler:481 - Setting redirect address to 'https://www.fuehrungszeugnis.bund.de/ffw/form/display.do;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?%24context=0EE2D2329C2215AF85BA'.
 -09-09 14:28:26,305+02 [Thread-8] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
 -09-09 14:28:26,305+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:211 - Recieved BindingResult with ResultCode REDIRECT
 -09-09 14:28:26,306+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:200 - BindingResult contains no body.
 -09-09 14:28:26,307+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:131 - HTTP response: HTTP/1.1 303  [Location: https://www.fuehrungszeugnis.bund.de/ffw/form/display.do;jsessionid=ECBE93DC138F90493826514C9630CCFA.nodevlp61080-02?ResultMajor=error&%24context=0EE2D2329C2215AF85BA&ResultMinor=serverError] [Content-Type: text/plain; charset=UTF-8,Content-Length: 52,Chunked: false]

(1-1/1)

Project

General

Profile

Open eCard

Bug #766 » richclient_info.log