Project

General

Profile

Bug #370 » richclient_info.log

Detlef Hühnlein, 03/07/2015 03:56 PM

 
1
2015-03-07 15:49:16,221 [javawsApplicationMain] INFO  o.o.richclient.LogbackConfig:70 - Configured Logback with config file from: C:\Users\H?hni\.openecard\richclient_logback.xml
2
2015-03-07 15:49:16,633 [javawsApplicationMain] WARN  org.openecard.common.I18n:151 - Failed to load resource recognition for lang de_DE.
3
2015-03-07 15:49:16,828 [javawsApplicationMain] WARN  org.openecard.common.I18n:151 - Failed to load resource http for lang de_DE.
4
2015-03-07 15:49:16,851 [javawsApplicationMain] DEBUG o.o.c.binding.http.HTTPService:73 - Starting HTTPBinding on port 24727
5
2015-03-07 15:49:16,874 [javawsApplicationMain] DEBUG o.o.c.binding.http.HTTPService:84 - Add handler [org.openecard.control.binding.http.handler.HttpAppPluginActionHandler] for ID [*]
6
2015-03-07 15:49:16,933 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:93 - Loaded internal TR-03112 add-on.
7
2015-03-07 15:49:16,944 [Init-File-Addons] INFO  org.openecard.addon.FileRegistry:293 - Loaded external addon esign-main-1.0-jar-with-dependencies.jar
8
2015-03-07 15:49:16,944 [Init-File-Addons] DEBUG org.openecard.addon.FileRegistry:147 - Starting file alteration monitor on path: C:\Users\H?hni\.openecard\addons
9
2015-03-07 15:49:16,947 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:93 - Loaded internal PIN-Management add-on.
10
2015-03-07 15:49:16,953 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:93 - Loaded internal GenericCrypto add-on.
11
2015-03-07 15:49:16,961 [Init-Classpath-Addons] INFO  o.openecard.addon.ClasspathRegistry:93 - Loaded internal Status add-on.
12
2015-03-07 15:49:16,974 [Init-Classpath-Addons] WARN  o.openecard.addon.ClasspathRegistry:88 - Skipped loading internal add-on PKCS#11, because it is not available.
13
2015-03-07 15:49:17,016 [Filesystem Alteration Monitor] INFO  o.o.a.PluginDirectoryAlterationListener:199 - The file eSign Services.conf is no .jar file. Ignoring the file.
14
2015-03-07 15:49:17,017 [Filesystem Alteration Monitor] ERROR o.o.a.PluginDirectoryAlterationListener:93 - The .jar file with the name eSign Services.conf does not seem to be an add-on or is an already registered add-on.
15
2015-03-07 15:49:17,017 [Filesystem Alteration Monitor] INFO  o.o.a.PluginDirectoryAlterationListener:199 - The file eSign Services.conf is no .jar file. Ignoring the file.
16
2015-03-07 15:49:17,018 [Filesystem Alteration Monitor] ERROR o.o.a.PluginDirectoryAlterationListener:93 - The .jar file with the name eSign Services.conf does not seem to be an add-on or is an already registered add-on.
17
2015-03-07 15:49:17,029 [Filesystem Alteration Monitor] DEBUG o.o.a.PluginDirectoryAlterationListener:149 - Addon esign-main-1.0-jar-with-dependencies.jar is already registered
18
2015-03-07 15:49:17,029 [Filesystem Alteration Monitor] ERROR o.o.a.PluginDirectoryAlterationListener:93 - The .jar file with the name esign-main-1.0-jar-with-dependencies.jar does not seem to be an add-on or is an already registered add-on.
19
2015-03-07 15:49:18,533 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:142 - Found a terminal added event (Broadcom Corp Contacted SmartCard 0).
20
2015-03-07 15:49:18,535 [pool-2-thread-2] DEBUG org.openecard.richclient.gui.Status:336 - Event: TERMINAL_ADDED
21
2015-03-07 15:49:18,536 [pool-2-thread-2] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@61853d
22
2015-03-07 15:49:18,536 [pool-2-thread-2] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: null
23
2015-03-07 15:49:18,536 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:164 - Found a card insert event (Broadcom Corp Contacted SmartCard 0).
24
2015-03-07 15:49:18,536 [pool-2-thread-2] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
25
2015-03-07 15:49:18,536 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_INSERTED
26
2015-03-07 15:49:18,537 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@f86336
27
2015-03-07 15:49:18,537 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@3f8a19
28
2015-03-07 15:49:18,537 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
29
2015-03-07 15:49:18,538 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:142 - Found a terminal added event (REINER SCT cyberJack wave USB 1).
30
2015-03-07 15:49:18,538 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:164 - Found a card insert event (REINER SCT cyberJack wave USB 1).
31
2015-03-07 15:49:18,538 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_INSERTED
32
2015-03-07 15:49:18,539 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@5dc1e3
33
2015-03-07 15:49:18,539 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@9a3958
34
2015-03-07 15:49:18,539 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: REINER SCT cyberJack wave USB 1
35
2015-03-07 15:49:18,540 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:336 - Event: TERMINAL_ADDED
36
2015-03-07 15:49:18,540 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@dde0fe
37
2015-03-07 15:49:18,540 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: null
38
2015-03-07 15:49:18,540 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: REINER SCT cyberJack wave USB 1
39
2015-03-07 15:49:18,553 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
40
2015-03-07 15:49:18,553 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='Broadcom Corp Contacted SmartCard 0' cardPresent=true
41
2015-03-07 15:49:18,557 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack wave USB 1' cardPresent=true
42
2015-03-07 15:49:18,729 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
43
2015-03-07 15:49:18,729 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
44
2015-03-07 15:49:18,772 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
45
2015-03-07 15:49:18,772 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
46
2015-03-07 15:49:18,784 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
47
2015-03-07 15:49:18,787 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
48
2015-03-07 15:49:18,801 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
49
2015-03-07 15:49:18,802 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
50
2015-03-07 15:49:18,816 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
51
2015-03-07 15:49:18,816 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
52
2015-03-07 15:49:18,823 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
53
2015-03-07 15:49:18,824 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
54
2015-03-07 15:49:18,825 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6D 0x00 
55
2015-03-07 15:49:18,826 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
56
2015-03-07 15:49:18,837 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
57
2015-03-07 15:49:18,838 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
58
2015-03-07 15:49:18,839 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
59
2015-03-07 15:49:18,839 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
60
2015-03-07 15:49:18,847 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
61
2015-03-07 15:49:18,848 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
62
2015-03-07 15:49:18,855 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
63
2015-03-07 15:49:18,855 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
64
2015-03-07 15:49:18,857 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
65
2015-03-07 15:49:18,857 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
66
2015-03-07 15:49:18,867 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
67
2015-03-07 15:49:18,868 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
68
2015-03-07 15:49:18,875 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6D 0x00 
69
2015-03-07 15:49:18,876 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
70
2015-03-07 15:49:18,876 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
71
2015-03-07 15:49:18,877 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
72
2015-03-07 15:49:18,886 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
73
2015-03-07 15:49:18,887 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
74
2015-03-07 15:49:18,897 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
75
2015-03-07 15:49:18,897 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
76
2015-03-07 15:49:18,898 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x61 0x0E 0x4F 0x0C 0xA0 0x00 0x00 0x02 0x44 0x46 0x5F 0x43 0x65 0x72 0x74 0x73 0x62 0x82 
77
2015-03-07 15:49:18,902 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
78
2015-03-07 15:49:18,906 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x67 0x00 
79
2015-03-07 15:49:18,906 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
80
2015-03-07 15:49:18,915 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
81
2015-03-07 15:49:18,915 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
82
2015-03-07 15:49:18,915 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
83
2015-03-07 15:49:18,916 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
84
2015-03-07 15:49:18,926 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
85
2015-03-07 15:49:18,927 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
86
2015-03-07 15:49:18,931 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
87
2015-03-07 15:49:18,931 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
88
2015-03-07 15:49:18,938 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x61 0x32 0x4F 0x0F 0xE8 0x28 0xBD 0x08 0x0F 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x50 0x0F 0x43 0x49 0x41 0x20 0x7A 0x75 0x20 0x44 0x46 0x2E 0x65 0x53 0x69 0x67 0x6E 0x51 0x00 0x73 0x0C 0x4F 0x0A 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x61 0x09 0x4F 0x07 0xA0 0x00 0x00 0x02 0x47 0x10 0x01 0x61 0x0B 0x4F 0x09 0xE8 0x07 0x04 0x00 0x7F 0x00 0x07 0x03 0x02 0x61 0x0C 0x4F 0x0A 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x62 0x82 
89
2015-03-07 15:49:18,940 [pool-2-thread-10] DEBUG org.openecard.event.Recognizer:55 - Found a recognized card event (REINER SCT cyberJack wave USB 1).
90
2015-03-07 15:49:18,941 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_RECOGNIZED
91
2015-03-07 15:49:18,941 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@14f4036
92
2015-03-07 15:49:18,941 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@19735c3
93
2015-03-07 15:49:18,941 [pool-2-thread-5] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: REINER SCT cyberJack wave USB 1
94
2015-03-07 15:49:18,942 [pool-2-thread-6] INFO  o.o.c.sal.state.SALStateCallback:60 - Add ConnectionHandle to SAL:
95
ConnectionHandle:
96
  Session: MXjDNRYnVqoKxO0L_CLVfg
97
  ContextHandle: 8D710CE1C2AE8CD45D7F8FE270C8DECC
98
  IFDName: REINER SCT cyberJack wave USB 1  SlotIndex: 0
99
  CardType: http://bsi.bund.de/cif/npa.xml
100
2015-03-07 15:49:18,952 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x5A 0x0A 0x92 0x76 0x00 0x32 0x10 0x20 0x03 0x05 0x70 0x3F 0x62 0x82 
101
2015-03-07 15:49:18,952 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
102
2015-03-07 15:49:18,968 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
103
2015-03-07 15:49:18,969 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
104
2015-03-07 15:49:18,987 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
105
2015-03-07 15:49:18,987 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
106
2015-03-07 15:49:19,009 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x61 0x0E 0x4F 0x0C 0xA0 0x00 0x00 0x00 0x63 0x50 0x4B 0x43 0x53 0x2D 0x31 0x35 0x62 0x82 
107
2015-03-07 15:49:19,009 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
108
2015-03-07 15:49:19,035 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
109
2015-03-07 15:49:19,035 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
110
2015-03-07 15:49:19,061 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
111
2015-03-07 15:49:19,061 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
112
2015-03-07 15:49:19,086 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
113
2015-03-07 15:49:19,086 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
114
2015-03-07 15:49:19,102 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
115
2015-03-07 15:49:19,102 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
116
2015-03-07 15:49:19,123 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
117
2015-03-07 15:49:19,124 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
118
2015-03-07 15:49:19,140 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x69 0x81 
119
2015-03-07 15:49:19,145 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
120
2015-03-07 15:49:19,160 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
121
2015-03-07 15:49:19,161 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x01 0x0C 0x02 0x50 0x15 
122
2015-03-07 15:49:19,178 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
123
2015-03-07 15:49:19,178 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x50 0x32 
124
2015-03-07 15:49:19,195 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
125
2015-03-07 15:49:19,195 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
126
2015-03-07 15:49:19,218 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x3B 0x02 0x01 0x00 0x04 0x00 0x0C 0x10 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x47 0x6D 0x62 0x48 0x20 0x28 0x43 0x29 0x80 0x1E 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x43 0x61 0x72 0x64 0x20 0x56 0x33 0x2E 0x30 0x20 0x73 0x74 0x61 0x6E 0x64 0x61 0x72 0x64 0x20 0x32 0x67 0x61 0x03 0x02 0x04 0x50 0x62 0x82 
127
2015-03-07 15:49:19,218 [pool-2-thread-4] DEBUG org.openecard.event.Recognizer:55 - Found a recognized card event (Broadcom Corp Contacted SmartCard 0).
128
2015-03-07 15:49:19,218 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_RECOGNIZED
129
2015-03-07 15:49:19,218 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@acaf92
130
2015-03-07 15:49:19,219 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@1edc60b
131
2015-03-07 15:49:19,219 [pool-2-thread-10] INFO  o.o.c.sal.state.SALStateCallback:60 - Add ConnectionHandle to SAL:
132
ConnectionHandle:
133
  Session: MXjDNRYnVqoKxO0L_CLVfg
134
  ContextHandle: 8D710CE1C2AE8CD45D7F8FE270C8DECC
135
  IFDName: Broadcom Corp Contacted SmartCard 0  SlotIndex: 0
136
  CardType: https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3
137
2015-03-07 15:49:19,219 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
138
2015-03-07 15:49:32,115 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /getStatus?_=1425739771411 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
139
2015-03-07 15:49:32,115 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /eID-Client?status&_=1425739771412 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: */*, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
140
2015-03-07 15:49:32,141 [Thread-14] WARN  org.openecard.common.I18n:151 - Failed to load resource tr03112 for lang de_DE.
141
2015-03-07 15:49:32,167 [Thread-14] WARN  org.openecard.common.I18n:151 - Failed to load resource tctoken for lang de_DE.
142
2015-03-07 15:49:32,170 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode MISSING_PARAMETER
143
2015-03-07 15:49:32,171 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:142 - BindingResult contains no body.
144
2015-03-07 15:49:32,178 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 400  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 62,Chunked: false]
145
2015-03-07 15:49:32,181 [Thread-14] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:97 - HTTP response intercepted
146
2015-03-07 15:49:32,505 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
147
2015-03-07 15:49:32,505 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
148
2015-03-07 15:49:32,505 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 6562,Chunked: false]
149
2015-03-07 15:49:32,539 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /getStatus?session=5C20EED1DDC8969D862D8864C73D4E7B&_=1425739771413 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
150
2015-03-07 15:49:32,943 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
151
2015-03-07 15:49:32,944 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
152
2015-03-07 15:49:32,944 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 6562,Chunked: false]
153
2015-03-07 15:49:32,959 [Thread-17] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /waitForChange?session=5C20EED1DDC8969D862D8864C73D4E7B&_=1425739771414 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
154
2015-03-07 15:49:34,280 [Thread-18] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /eID-Client?cardType=https%3A%2F%2Fwww.d-trust.net%2Fprodukte%2Fd-trust-signaturkarten%2Fd-trust-card%2Fstandard_v3&tcTokenURL=https%3A%2F%2Fservice.skidentity.de%2Fbackend%2Ftls%2Factivate-client%3Fsession%3D21E2FF8956C8DAC51F6C89D58A4AEDC5%26type%3Dhttps%3A%2F%2Fwww.d-trust.net%2Fprodukte%2Fd-trust-signaturkarten%2Fd-trust-card%2Fstandard_v3%26protocol%3Durn%3Aoid%3A1.3.162.15480.3.0.25%26activation-type%3DeID-Client [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Connection: keep-alive]
155
2015-03-07 15:49:34,300 [Thread-18] INFO  o.o.binding.tctoken.ResourceContext:198 - Trying to load resource from: https://service.skidentity.de/backend/tls/activate-client?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&type=https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3&protocol=urn:oid:1.3.162.15480.3.0.25&activation-type=eID-Client
156
2015-03-07 15:49:34,324 [Thread-18] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
157
2015-03-07 15:49:34,324 [Thread-18] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
158
2015-03-07 15:49:34,372 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:231 - Performing TLS handshake.
159
2015-03-07 15:49:34,543 [Thread-18] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
160
2015-03-07 15:49:34,735 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:233 - TLS handshake performed.
161
2015-03-07 15:49:34,753 [Thread-18] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
162
  GET /backend/tls/activate-client?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&type=https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3&protocol=urn:oid:1.3.162.15480.3.0.25&activation-type=eID-Client HTTP/1.1
163
  Connection: keep-alive
164
  User-Agent: Open-eCard-App/1.1.0-rc8
165
  Host: service.skidentity.de
166
  Accept: text/xml, */*;q=0.8
167
  Accept-Charset: utf-8, *;q=0.8
168

    
169
2015-03-07 15:49:34,753 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:254 - Sending HTTP request.
170
2015-03-07 15:49:34,803 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:256 - HTTP response received.
171
2015-03-07 15:49:34,804 [Thread-18] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
172
  HTTP/1.1 200 OK
173
  Date: Sat, 07 Mar 2015 14:49:38 GMT
174
  Server: Apache/2.4.10 (Debian)
175
  Vary: Accept-Encoding
176
  Access-Control-Allow-Headers: accept, content-type
177
  Access-Control-Allow-Origin: http://service.skidentity.de
178
  Access-Control-Allow-Methods: GET, POST, OPTIONS
179
  Access-Control-Allow-Credentials: true
180
  Content-Length: 595
181
  Keep-Alive: timeout=5, max=100
182
  Connection: Keep-Alive
183
  Content-Type: text/xml;charset=UTF-8
184

    
185

    
186
2015-03-07 15:49:34,807 [Thread-18] DEBUG o.o.binding.tctoken.TCTokenContext:91 - Cleaned up TCToken:
187
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
188
<TCTokenType>
189
  <ServerAddress>https://service.skidentity.de:443/backend/tls/cert-authenticate?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&amp;client-type=eID-Client</ServerAddress>
190
  <SessionIdentifier>21E2FF8956C8DAC51F6C89D58A4AEDC5</SessionIdentifier>
191
  <RefreshAddress>https://service.skidentity.de:443/backend/tls/terminate-client?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&amp;client-type=eID-Client</RefreshAddress>
192
  <Binding>urn:ietf:rfc:2616</Binding>
193
  <PathSecurity-Protocol>urn:ietf:rfc:4346</PathSecurity-Protocol>
194
</TCTokenType>
195

    
196
2015-03-07 15:49:34,821 [Thread-18] WARN  o.o.binding.tctoken.TCTokenHandler:289 - Checks according to BSI TR03112 3.4.2, 3.4.4 (TCToken specific) and 3.4.5 are disabled.
197
2015-03-07 15:49:34,859 [Thread-18] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationPath
198
2015-03-07 15:49:34,864 [Thread-18] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
199
2015-03-07 15:49:34,864 [Thread-18] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
200
2015-03-07 15:49:34,867 [Thread-18] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
201
2015-03-07 15:49:34,867 [Thread-18] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x07 0xD2 0x76 0x00 0x01 0x44 0x80 0x00 
202
2015-03-07 15:49:34,890 [Thread-18] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
203
2015-03-07 15:49:34,895 [TLS Auth] INFO  o.o.c.t.ClientCertDefaultTlsClient:215 - TLS warning sent.
204
2015-03-07 15:49:34,895 [TLS Auth] INFO  o.o.c.t.ClientCertDefaultTlsClient:216 - TLS(WARN): Close [close_notify=0] --> Connection closed
205
2015-03-07 15:49:34,901 [TLS Auth] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
206
2015-03-07 15:49:34,901 [TLS Auth] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
207
2015-03-07 15:49:35,007 [TLS Auth] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
208
2015-03-07 15:49:35,127 [TLS Auth] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
209
  GET /backend/tls/cert-authenticate?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&client-type=eID-Client HTTP/1.1
210
  Connection: keep-alive
211
  User-Agent: Open-eCard-App/1.1.0-rc8
212
  Host: service.skidentity.de:443
213
  Accept: text/html; */*
214

    
215
2015-03-07 15:49:35,230 [TLS Auth] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
216
2015-03-07 15:49:35,237 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationList
217
2015-03-07 15:49:35,239 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDList
218
2015-03-07 15:49:35,240 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDList
219
2015-03-07 15:49:35,242 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDGet
220
2015-03-07 15:49:35,244 [TLS Auth] DEBUG o.o.c.c.s.GenericCryptoSignerFinder:236 - DTRUSTAuthenticationKeyPKCS1 is usable for TLSv1.1 and TLS1.2 signatures.
221
2015-03-07 15:49:35,244 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationList
222
2015-03-07 15:49:35,244 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
223
2015-03-07 15:49:35,244 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
224
2015-03-07 15:49:35,245 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
225
2015-03-07 15:49:35,245 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
226
2015-03-07 15:49:35,245 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
227
2015-03-07 15:49:35,245 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
228
2015-03-07 15:49:35,246 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
229
2015-03-07 15:49:35,247 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0C 0xA0 0x00 0x00 0x02 0x44 0x46 0x5F 0x43 0x65 0x72 0x74 0x73 
230
2015-03-07 15:49:35,281 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
231
2015-03-07 15:49:35,282 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.ACLList
232
2015-03-07 15:49:35,284 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetSelect
233
2015-03-07 15:49:35,288 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
234
2015-03-07 15:49:35,288 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x04 0x02 0xC1 0x00 0xFF 
235
2015-03-07 15:49:35,311 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x62 0x1A 0x80 0x02 0x0E 0x00 0xC5 0x02 0x05 0x7D 0x82 0x01 0x29 0x83 0x02 0xC1 0x00 0x88 0x01 0x80 0x8A 0x01 0x05 0xA1 0x03 0x8B 0x01 0x02 0x90 0x00 
236
2015-03-07 15:49:35,317 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DSIRead
237
2015-03-07 15:49:35,319 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
238
2015-03-07 15:49:35,320 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
239
2015-03-07 15:49:35,371 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x82 0x05 0x79 0x30 0x82 0x04 0x61 0xA0 0x03 0x02 0x01 0x02 0x02 0x03 0x0F 0xF3 0x5E 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x05 0x05 0x00 0x30 0x4E 0x31 0x0B 0x30 0x09 0x06 0x03 0x55 0x04 0x06 0x13 0x02 0x44 0x45 0x31 0x15 0x30 0x13 0x06 0x03 0x55 0x04 0x0A 0x0C 0x0C 0x44 0x2D 0x54 0x72 0x75 0x73 0x74 0x20 0x47 0x6D 0x62 0x48 0x31 0x28 0x30 0x26 0x06 0x03 0x55 0x04 0x03 0x0C 0x1F 0x54 0x65 0x73 0x74 0x20 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x41 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x20 0x32 0x30 0x31 0x31 0x20 0x43 0x41 0x20 0x31 0x30 0x1E 0x17 0x0D 0x31 0x33 0x31 0x30 0x33 0x30 0x30 0x36 0x30 0x32 0x34 0x39 0x5A 0x17 0x0D 0x31 0x34 0x31 0x30 0x33 0x30 0x30 0x36 0x30 0x32 0x34 0x39 0x5A 0x30 0x81 0xB0 0x31 0x0B 0x30 0x09 0x06 0x03 0x55 0x04 0x06 0x13 0x02 0x44 0x45 0x31 0x13 0x30 0x11 0x06 0x03 0x55 0x04 0x0A 0x0C 0x0A 0x65 0x63 0x73 0x65 0x63 0x20 0x47 0x6D 0x62 0x48 0x31 0x23 0x30 0x21 0x06 0x03 0x55 0x04 0x0B 0x0C 0x1A 0x46 0x6F 0x72 0x20 0x74 0x65 0x73 0x74 0x69 0x6E 0x67 0x20 0x70 0x75 0x72 0x70 0x6F 0x73 0x65 0x73 0x20 0x6F 0x6E 0x6C 0x79 0x21 0x31 0x19 0x30 0x17 0x06 0x03 0x55 0x04 0x03 0x0C 0x10 0x44 0x65 0x74 0x6C 0x65 0x66 0x20 0x48 0xC3 0xBC 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x31 0x19 0x30 0x17 0x06 0x03 0x55 0x04 0x2A 0x90 0x00 
240
2015-03-07 15:49:35,372 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
241
2015-03-07 15:49:35,372 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0xFF 0xFF 
242
2015-03-07 15:49:35,419 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x0C 0x10 0x44 0x65 0x74 0x6C 0x65 0x66 0x20 0x57 0x69 0x6C 0x6C 0x69 0x62 0x61 0x6C 0x64 0x31 0x12 0x30 0x10 0x06 0x03 0x55 0x04 0x04 0x0C 0x09 0x48 0xC3 0xBC 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x31 0x1D 0x30 0x1B 0x06 0x03 0x55 0x04 0x05 0x13 0x14 0x44 0x54 0x52 0x57 0x4D 0x33 0x38 0x32 0x38 0x36 0x30 0x36 0x30 0x33 0x38 0x37 0x30 0x39 0x31 0x31 0x30 0x82 0x01 0x22 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x01 0x05 0x00 0x03 0x82 0x01 0x0F 0x00 0x30 0x82 0x01 0x0A 0x02 0x82 0x01 0x01 0x00 0xAC 0x88 0xDF 0xDF 0xF6 0xD1 0x59 0x41 0x28 0x57 0x66 0x1E 0xDD 0xDF 0x51 0x5C 0x29 0xC8 0xC5 0xE8 0x90 0x3E 0xD6 0x0D 0x05 0xEB 0x67 0xEA 0x90 0xC4 0x84 0x51 0xA9 0x7F 0xB0 0x52 0xB8 0x77 0x00 0xC5 0x75 0xAD 0x22 0x33 0xE0 0x14 0x92 0xDF 0x27 0xF9 0xCA 0xA1 0x57 0x1B 0xCF 0x88 0x24 0xE2 0x38 0xC5 0x5F 0x32 0xC2 0xA8 0xA3 0x31 0xAE 0xED 0x55 0xFE 0x96 0xC1 0xB1 0x11 0x35 0xBA 0x74 0x83 0xC2 0x83 0xC5 0xCD 0x67 0xE8 0xCA 0x28 0xD4 0xEE 0x17 0xD5 0xF7 0xD7 0xAB 0xDE 0x92 0x84 0xE8 0xFB 0xE1 0xC0 0x20 0xAB 0xEA 0xF7 0x74 0xA6 0xCB 0x59 0x8A 0x26 0x5D 0x37 0xC4 0x70 0x7C 0xBF 0x39 0x14 0x93 0x14 0x78 0xC5 0x28 0xD6 0xEA 0x26 0x12 0x6C 0xE3 0x6D 0xD5 0xB1 0xC6 0x9D 0x2D 0x34 0x68 0x2B 0xB7 0x13 0xA7 0x28 0x3E 0x4B 0xC8 0xDB 0x0F 0xBC 0x2B 0xEA 0x0D 0x0A 0xF5 0x90 0x00 
243
2015-03-07 15:49:35,426 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
244
2015-03-07 15:49:35,427 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x01 0xFE 0xFF 
245
2015-03-07 15:49:35,466 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0xCF 0xAF 0x25 0xF3 0x01 0x9E 0x36 0xE3 0x15 0x50 0x66 0x57 0x26 0x8A 0x8F 0x9C 0xDF 0x28 0x48 0xA5 0x80 0x83 0x56 0xB6 0x2D 0x64 0xC4 0xC0 0xA8 0x4F 0x91 0x0D 0xA2 0x8B 0x8C 0xFD 0xB3 0x7E 0xED 0x2D 0x47 0xAF 0x56 0xCF 0x34 0xD2 0xA1 0xB9 0x54 0x58 0x93 0x5A 0x00 0xA5 0x11 0x4F 0x82 0x90 0xAF 0xE5 0x05 0xC5 0xC4 0xE3 0xC5 0x70 0xEB 0x32 0xAC 0xFE 0x50 0xE7 0xF7 0x39 0x4E 0xF7 0x9F 0x60 0x71 0xAF 0x5C 0x38 0x9D 0x97 0xBE 0xD1 0x82 0x22 0x2B 0xDC 0x39 0xDF 0x57 0xE7 0x4B 0x1D 0xF5 0xC5 0x63 0x27 0xE3 0x83 0xF5 0x02 0x03 0x01 0x00 0x01 0xA3 0x82 0x01 0xFB 0x30 0x82 0x01 0xF7 0x30 0x23 0x06 0x03 0x55 0x1D 0x25 0x04 0x1C 0x30 0x1A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x03 0x02 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x03 0x04 0x06 0x04 0x55 0x1D 0x25 0x00 0x30 0x1F 0x06 0x03 0x55 0x1D 0x23 0x04 0x18 0x30 0x16 0x80 0x14 0xE8 0xF4 0x3F 0x58 0xB1 0x2D 0x07 0x85 0x08 0x80 0x01 0xED 0xB0 0x32 0xA9 0x28 0x3F 0x8A 0xEB 0x83 0x30 0x3A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x01 0x01 0x04 0x2E 0x30 0x2C 0x30 0x2A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x30 0x01 0x86 0x1E 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x6F 0x63 0x73 0x70 0x2D 0x73 0x68 0x61 0x32 0x35 0x36 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x30 0x17 0x06 0x03 0x55 0x1D 0x20 0x04 0x10 0x90 0x00 
246
2015-03-07 15:49:35,467 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
247
2015-03-07 15:49:35,467 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x02 0xFD 0xFF 
248
2015-03-07 15:49:35,507 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x0E 0x30 0x0C 0x06 0x0A 0x2B 0x06 0x01 0x04 0x01 0xA5 0x34 0x02 0x02 0x02 0x30 0x81 0xCF 0x06 0x03 0x55 0x1D 0x1F 0x04 0x81 0xC7 0x30 0x81 0xC4 0x30 0x81 0xC1 0xA0 0x81 0xBE 0xA0 0x81 0xBB 0x86 0x79 0x6C 0x64 0x61 0x70 0x3A 0x2F 0x2F 0x64 0x69 0x72 0x65 0x63 0x74 0x6F 0x72 0x79 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x2F 0x43 0x4E 0x3D 0x54 0x65 0x73 0x74 0x25 0x32 0x30 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x25 0x32 0x30 0x41 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x25 0x32 0x30 0x32 0x30 0x31 0x31 0x25 0x32 0x30 0x43 0x41 0x25 0x32 0x30 0x31 0x2C 0x4F 0x3D 0x44 0x2D 0x54 0x72 0x75 0x73 0x74 0x25 0x32 0x30 0x47 0x6D 0x62 0x48 0x2C 0x43 0x3D 0x44 0x45 0x3F 0x63 0x65 0x72 0x74 0x69 0x66 0x69 0x63 0x61 0x74 0x65 0x72 0x65 0x76 0x6F 0x63 0x61 0x74 0x69 0x6F 0x6E 0x6C 0x69 0x73 0x74 0x86 0x3E 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x77 0x77 0x77 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x2F 0x63 0x72 0x6C 0x2F 0x74 0x65 0x73 0x74 0x5F 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x5F 0x61 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x5F 0x32 0x30 0x31 0x31 0x5F 0x63 0x61 0x5F 0x31 0x2E 0x63 0x72 0x6C 0x30 0x33 0x06 0x03 0x55 0x1D 0x12 0x04 0x2C 0x30 0x2A 0x81 0x10 0x69 0x6E 0x66 0x6F 0x40 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x90 0x00 
249
2015-03-07 15:49:35,507 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
250
2015-03-07 15:49:35,507 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x03 0xFC 0xFF 
251
2015-03-07 15:49:35,545 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x86 0x16 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x77 0x77 0x77 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x30 0x1D 0x06 0x03 0x55 0x1D 0x0E 0x04 0x16 0x04 0x14 0x2C 0xB2 0x1D 0x88 0x15 0x63 0x83 0xCB 0xDB 0xA1 0x51 0x98 0x48 0x90 0x9C 0x90 0xFF 0x69 0x9E 0x75 0x30 0x0E 0x06 0x03 0x55 0x1D 0x0F 0x01 0x01 0xFF 0x04 0x04 0x03 0x02 0x04 0xB0 0x30 0x24 0x06 0x03 0x55 0x1D 0x11 0x04 0x1D 0x30 0x1B 0x81 0x19 0x64 0x65 0x74 0x6C 0x65 0x66 0x2E 0x68 0x75 0x65 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x40 0x65 0x63 0x73 0x65 0x63 0x2E 0x64 0x65 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x05 0x05 0x00 0x03 0x82 0x01 0x01 0x00 0x0F 0xD0 0xF9 0x63 0x86 0x88 0x5B 0x72 0xC3 0x18 0x16 0x60 0x8B 0xF8 0xF8 0xFF 0x79 0x64 0xAF 0x1B 0x2B 0x5C 0x33 0x00 0x7B 0xD2 0x35 0xCC 0x5A 0xBC 0x45 0x7D 0xDB 0xD3 0x46 0x62 0x77 0x7C 0x54 0xD6 0x2C 0x4B 0x30 0x22 0x94 0x29 0x17 0x12 0x66 0x36 0x41 0xF4 0x55 0x49 0xFA 0x76 0xA3 0xA2 0x93 0xD1 0x82 0x52 0xA8 0x57 0x64 0xA4 0x10 0x06 0x88 0x2E 0x15 0xB7 0x78 0x3E 0xF2 0xC0 0x75 0x47 0xCC 0xE0 0x1F 0x1C 0x62 0x71 0xF1 0x22 0x08 0x23 0x1E 0x9B 0x61 0x6E 0x8B 0xF3 0x22 0xF8 0x04 0xA6 0x41 0xA6 0x2B 0x46 0x2A 0x78 0x36 0xCE 0x37 0x81 0xDB 0xCE 0x6C 0x6B 0xF0 0xE9 0x7E 0x1E 0x63 0x51 0xC1 0x31 0x76 0xAD 0x31 0x56 0x46 0xB2 0x90 0x00 
252
2015-03-07 15:49:35,545 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
253
2015-03-07 15:49:35,545 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x04 0xFB 0xFF 
254
2015-03-07 15:49:35,567 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x8F 0xE2 0x2F 0x60 0xBF 0x88 0x56 0x88 0xCA 0x56 0x16 0xD7 0x84 0x6F 0x28 0xB5 0xEC 0x75 0xD6 0x92 0x38 0x1E 0x72 0x8B 0x36 0xFB 0x70 0xCA 0x9A 0xAF 0x2B 0xE9 0x32 0x9C 0xEC 0x3D 0x3C 0x34 0xCD 0x7D 0x92 0x2D 0x36 0x0E 0x1A 0x59 0x77 0xE4 0x95 0x4E 0x2E 0xEC 0xF0 0xEA 0x5B 0xA9 0xC9 0x77 0xC5 0xF1 0x31 0x13 0xE5 0xF6 0x59 0xDC 0xBB 0xBD 0xC4 0x55 0x36 0x88 0x51 0x4F 0x63 0xFF 0x90 0x45 0xB5 0x86 0x4E 0xD3 0x8A 0x3E 0x3C 0x28 0xCD 0x03 0x71 0x8C 0xFE 0x6F 0x74 0xD8 0x72 0x4E 0x74 0xBA 0x4A 0xDB 0xA3 0x44 0x28 0xC7 0x8B 0xAE 0xA1 0xA0 0xA2 0xE9 0xE0 0xDF 0x3B 0xCE 0x3C 0xB5 0x7A 0xA5 0xFB 0xB8 0x53 0x93 0x63 0x9F 0xE6 0x40 0xD6 0xCD 0xAF 0x0F 0x62 0x82 
255
2015-03-07 15:49:35,569 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.ACLList
256
2015-03-07 15:49:35,574 [TLS Auth] ERROR o.o.c.c.s.GenericCryptoSignerFinder:201 - Searching for DID failed
257
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/sal#namedEntityNotFound
258
  ==> The given DataSet cannot be found.
259
	at org.openecard.common.ECardException.makeException(ECardException.java:64) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
260
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
261
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
262
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.filterTLSCapableDIDs(GenericCryptoSignerFinder.java:294) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
263
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.findDID(GenericCryptoSignerFinder.java:189) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
264
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.findFirstMatching(GenericCryptoSignerFinder.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
265
	at org.openecard.crypto.tls.auth.SmartCardCredentialFactory.getClientCredentials(SmartCardCredentialFactory.java:61) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
266
	at org.openecard.crypto.tls.auth.DynamicAuthentication.getClientCredentials(DynamicAuthentication.java:173) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
267
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:363) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
268
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
269
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
270
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
271
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
272
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
273
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
274
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
275
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
276
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
277
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
278
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
279
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
280
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
281
	at java.io.BufferedInputStream.read1(Unknown Source) [na:1.8.0_31]
282
	at java.io.BufferedInputStream.read(Unknown Source) [na:1.8.0_31]
283
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
284
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
285
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
286
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
287
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
288
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
289
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
290
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
291
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
292
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
293
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
294
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
295
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
296
2015-03-07 15:49:35,575 [TLS Auth] ERROR o.o.c.t.a.SmartCardCredentialFactory:65 - No suitable credential found. Returning empty list.
297
2015-03-07 15:49:35,576 [TLS Auth] ERROR o.o.c.t.ClientCertDefaultTlsClient:218 - TLS error sent.
298
2015-03-07 15:49:35,576 [TLS Auth] ERROR o.o.c.t.ClientCertDefaultTlsClient:219 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
299
org.openecard.bouncycastle.crypto.tls.TlsFatalAlert: null
300
	at org.openecard.bouncycastle.crypto.tls.TlsECDHEKeyExchange.processClientCredentials(TlsECDHEKeyExchange.java:210) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
301
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:379) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
302
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
303
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
304
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
305
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
306
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
307
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
308
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
309
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
310
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
311
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
312
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
313
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
314
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
315
	at java.io.BufferedInputStream.read1(Unknown Source) [na:1.8.0_31]
316
	at java.io.BufferedInputStream.read(Unknown Source) [na:1.8.0_31]
317
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
318
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
319
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
320
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
321
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
322
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
323
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
324
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
325
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
326
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
327
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
328
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
329
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
330
2015-03-07 15:49:35,577 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationDisconnect
331
2015-03-07 15:49:35,577 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
332
2015-03-07 15:49:35,578 [Thread-18] ERROR o.o.binding.tctoken.TCTokenHandler:413 - java.io.IOException: Internal TLS error, this could be an attack
333
java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
334
	at java.util.concurrent.FutureTask.report(Unknown Source) ~[na:1.8.0_31]
335
	at java.util.concurrent.FutureTask.get(Unknown Source) ~[na:1.8.0_31]
336
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:408) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
337
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:222) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
338
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:336) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
339
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:94) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
340
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
341
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:111) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
342
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
343
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
344
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
345
Caused by: java.io.IOException: Internal TLS error, this could be an attack
346
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.failWithError(TlsProtocol.java:655) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
347
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:475) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
348
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
349
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
350
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
351
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
352
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
353
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
354
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
355
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
356
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
357
	at java.io.BufferedInputStream.read1(Unknown Source) ~[na:1.8.0_31]
358
	at java.io.BufferedInputStream.read(Unknown Source) ~[na:1.8.0_31]
359
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
360
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
361
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
362
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
363
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
364
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
365
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
366
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
367
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
368
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
369
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
370
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_31]
371
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_31]
372
2015-03-07 15:49:35,578 [Thread-18] ERROR o.o.binding.tctoken.TCTokenHandler:349 - java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
373
org.openecard.transport.paos.PAOSException: java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
374
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:420) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
375
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:222) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
376
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:336) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
377
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:94) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
378
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
379
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:111) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
380
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
381
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
382
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
383
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
384
	at java.util.concurrent.FutureTask.report(Unknown Source) ~[na:1.8.0_31]
385
	at java.util.concurrent.FutureTask.get(Unknown Source) ~[na:1.8.0_31]
386
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:408) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
387
	... 8 common frames omitted
388
Caused by: java.io.IOException: Internal TLS error, this could be an attack
389
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.failWithError(TlsProtocol.java:655) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
390
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:475) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
391
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
392
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
393
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
394
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
395
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
396
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
397
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
398
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
399
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
400
	at java.io.BufferedInputStream.read1(Unknown Source) ~[na:1.8.0_31]
401
	at java.io.BufferedInputStream.read(Unknown Source) ~[na:1.8.0_31]
402
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
403
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
404
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
405
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
406
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
407
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
408
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
409
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
410
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
411
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
412
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
413
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_31]
414
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_31]
415
2015-03-07 15:49:35,582 [Thread-18] INFO  o.o.binding.tctoken.ResourceContext:198 - Trying to load resource from: https://service.skidentity.de:443/backend/tls/terminate-client?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&client-type=eID-Client
416
2015-03-07 15:49:35,582 [Thread-18] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
417
2015-03-07 15:49:35,583 [Thread-18] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
418
2015-03-07 15:49:35,619 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:231 - Performing TLS handshake.
419
2015-03-07 15:49:35,680 [Thread-18] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
420
2015-03-07 15:49:35,798 [Thread-18] DEBUG o.o.binding.tctoken.ResourceContext:233 - TLS handshake performed.
421
2015-03-07 15:49:35,798 [Thread-18] INFO  o.o.c.t.ClientCertDefaultTlsClient:215 - TLS warning sent.
422
2015-03-07 15:49:35,798 [Thread-18] INFO  o.o.c.t.ClientCertDefaultTlsClient:216 - TLS(WARN): Close [close_notify=0] --> Connection closed
423
2015-03-07 15:49:35,799 [Thread-18] DEBUG o.o.binding.tctoken.TCTokenHandler:469 - Setting redirect address to 'https://service.skidentity.de:443/backend/tls/terminate-client?session=21E2FF8956C8DAC51F6C89D58A4AEDC5&client-type=eID-Client'.
424
2015-03-07 15:49:35,801 [Thread-18] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode REDIRECT
425
2015-03-07 15:49:35,801 [Thread-18] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:142 - BindingResult contains no body.
426
2015-03-07 15:49:35,801 [Thread-18] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 303  [Location: https://service.skidentity.de:443/backend/tls/terminate-client?client-type=eID-Client&ResultMinor=incorrectParameter&ResultMajor=error&session=21E2FF8956C8DAC51F6C89D58A4AEDC5] [Content-Type: text/plain; charset=UTF-8,Content-Length: 64,Chunked: false]
427
2015-03-07 15:49:53,259 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:184 - Found a card removed event (Broadcom Corp Contacted SmartCard 0).
428
2015-03-07 15:49:53,259 [Thread-17] DEBUG org.openecard.addon.EventHandler:82 - WaitForChange event pulled from event queue.
429
2015-03-07 15:49:53,259 [pool-2-thread-5] INFO  o.o.c.sal.state.SALStateCallback:69 - Remove ConnectionHandle from SAL.
430
ConnectionHandle:
431
  Session: MXjDNRYnVqoKxO0L_CLVfg
432
  ContextHandle: 8D710CE1C2AE8CD45D7F8FE270C8DECC
433
  IFDName: Broadcom Corp Contacted SmartCard 0  SlotIndex: 0
434
2015-03-07 15:49:53,259 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_REMOVED
435
2015-03-07 15:49:53,260 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1e0ccd4
436
2015-03-07 15:49:53,260 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: null
437
2015-03-07 15:49:53,260 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
438
2015-03-07 15:49:53,263 [Thread-17] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
439
2015-03-07 15:49:53,264 [Thread-17] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
440
2015-03-07 15:49:53,264 [Thread-17] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 1337,Chunked: false]
441
2015-03-07 15:49:53,269 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
442
2015-03-07 15:49:53,270 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='Broadcom Corp Contacted SmartCard 0' cardPresent=false
443
2015-03-07 15:49:53,270 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack wave USB 1' cardPresent=true
444
2015-03-07 15:49:54,832 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:164 - Found a card insert event (Broadcom Corp Contacted SmartCard 0).
445
2015-03-07 15:49:54,834 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_INSERTED
446
2015-03-07 15:49:54,835 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@2bd6c7
447
2015-03-07 15:49:54,835 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@3af2af
448
2015-03-07 15:49:54,836 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
449
2015-03-07 15:49:54,853 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
450
2015-03-07 15:49:54,855 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:201 - Detecting initial terminal status.
451
2015-03-07 15:49:54,856 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='Broadcom Corp Contacted SmartCard 0' cardPresent=true
452
2015-03-07 15:49:54,856 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:205 - Terminal='REINER SCT cyberJack wave USB 1' cardPresent=true
453
2015-03-07 15:49:54,938 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
454
2015-03-07 15:49:54,939 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
455
2015-03-07 15:49:54,952 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
456
2015-03-07 15:49:54,953 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
457
2015-03-07 15:49:54,969 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
458
2015-03-07 15:49:54,970 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
459
2015-03-07 15:49:54,993 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
460
2015-03-07 15:49:54,994 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
461
2015-03-07 15:49:55,017 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x61 0x0E 0x4F 0x0C 0xA0 0x00 0x00 0x02 0x44 0x46 0x5F 0x43 0x65 0x72 0x74 0x73 0x62 0x82 
462
2015-03-07 15:49:55,018 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
463
2015-03-07 15:49:55,034 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
464
2015-03-07 15:49:55,034 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
465
2015-03-07 15:49:55,051 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
466
2015-03-07 15:49:55,052 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
467
2015-03-07 15:49:55,072 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x5A 0x0A 0x92 0x76 0x00 0x32 0x10 0x20 0x03 0x05 0x70 0x3F 0x62 0x82 
468
2015-03-07 15:49:55,073 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
469
2015-03-07 15:49:55,089 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
470
2015-03-07 15:49:55,090 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
471
2015-03-07 15:49:55,111 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
472
2015-03-07 15:49:55,112 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
473
2015-03-07 15:49:55,134 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x61 0x0E 0x4F 0x0C 0xA0 0x00 0x00 0x00 0x63 0x50 0x4B 0x43 0x53 0x2D 0x31 0x35 0x62 0x82 
474
2015-03-07 15:49:55,134 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
475
2015-03-07 15:49:55,158 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
476
2015-03-07 15:49:55,158 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
477
2015-03-07 15:49:55,183 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
478
2015-03-07 15:49:55,184 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
479
2015-03-07 15:49:55,209 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x6A 0x82 
480
2015-03-07 15:49:55,210 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
481
2015-03-07 15:49:55,227 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
482
2015-03-07 15:49:55,228 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
483
2015-03-07 15:49:55,250 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
484
2015-03-07 15:49:55,251 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
485
2015-03-07 15:49:55,270 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x69 0x81 
486
2015-03-07 15:49:55,271 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
487
2015-03-07 15:49:55,287 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
488
2015-03-07 15:49:55,287 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x01 0x0C 0x02 0x50 0x15 
489
2015-03-07 15:49:55,306 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
490
2015-03-07 15:49:55,307 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x50 0x32 
491
2015-03-07 15:49:55,326 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
492
2015-03-07 15:49:55,326 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
493
2015-03-07 15:49:55,347 [pool-2-thread-6] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x3B 0x02 0x01 0x00 0x04 0x00 0x0C 0x10 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x47 0x6D 0x62 0x48 0x20 0x28 0x43 0x29 0x80 0x1E 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x43 0x61 0x72 0x64 0x20 0x56 0x33 0x2E 0x30 0x20 0x73 0x74 0x61 0x6E 0x64 0x61 0x72 0x64 0x20 0x32 0x67 0x61 0x03 0x02 0x04 0x50 0x62 0x82 
494
2015-03-07 15:49:55,347 [pool-2-thread-6] DEBUG org.openecard.event.Recognizer:55 - Found a recognized card event (Broadcom Corp Contacted SmartCard 0).
495
2015-03-07 15:49:55,348 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:336 - Event: CARD_RECOGNIZED
496
2015-03-07 15:49:55,348 [pool-2-thread-10] INFO  o.o.c.sal.state.SALStateCallback:60 - Add ConnectionHandle to SAL:
497
ConnectionHandle:
498
  Session: MXjDNRYnVqoKxO0L_CLVfg
499
  ContextHandle: 8D710CE1C2AE8CD45D7F8FE270C8DECC
500
  IFDName: Broadcom Corp Contacted SmartCard 0  SlotIndex: 0
501
  CardType: https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3
502
2015-03-07 15:49:55,348 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:340 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@15d403c
503
2015-03-07 15:49:55,348 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:342 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@390b4c
504
2015-03-07 15:49:55,348 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:344 - IFDName: Broadcom Corp Contacted SmartCard 0
505
2015-03-07 15:50:03,422 [Thread-20] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /getStatus?_=1425739802671 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
506
2015-03-07 15:50:03,422 [Thread-21] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /eID-Client?status&_=1425739802672 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: */*, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
507
2015-03-07 15:50:03,422 [Thread-21] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode MISSING_PARAMETER
508
2015-03-07 15:50:03,423 [Thread-21] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:142 - BindingResult contains no body.
509
2015-03-07 15:50:03,423 [Thread-21] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 400  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 62,Chunked: false]
510
2015-03-07 15:50:03,423 [Thread-21] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:97 - HTTP response intercepted
511
2015-03-07 15:50:03,665 [Thread-20] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
512
2015-03-07 15:50:03,665 [Thread-20] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
513
2015-03-07 15:50:03,665 [Thread-20] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 6562,Chunked: false]
514
2015-03-07 15:50:03,694 [Thread-22] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /getStatus?session=E58591765E0EE04A7E5CA77FCB12CEA3&_=1425739802673 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
515
2015-03-07 15:50:03,920 [Thread-22] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
516
2015-03-07 15:50:03,921 [Thread-22] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
517
2015-03-07 15:50:03,921 [Thread-22] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 6562,Chunked: false]
518
2015-03-07 15:50:03,927 [Thread-24] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /waitForChange?session=E58591765E0EE04A7E5CA77FCB12CEA3&_=1425739802674 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
519
2015-03-07 15:50:06,682 [Thread-25] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /getStatus?_=1425739802675 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: application/xml, text/xml, */*; q=0.01, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
520
2015-03-07 15:50:06,683 [Thread-26] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /eID-Client?status&_=1425739802676 [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: */*, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Origin: http://service.skidentity.de, Connection: keep-alive]
521
2015-03-07 15:50:06,684 [Thread-26] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode MISSING_PARAMETER
522
2015-03-07 15:50:06,684 [Thread-26] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:142 - BindingResult contains no body.
523
2015-03-07 15:50:06,684 [Thread-26] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 400  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 62,Chunked: false]
524
2015-03-07 15:50:06,685 [Thread-26] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:97 - HTTP response intercepted
525
2015-03-07 15:50:06,932 [Thread-25] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode OK
526
2015-03-07 15:50:06,932 [Thread-25] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:132 - BindingResult contains a body.
527
2015-03-07 15:50:06,933 [Thread-25] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 200  [] [Content-Type: text/xml; charset=UTF-8,Content-Length: 6562,Chunked: false]
528
2015-03-07 15:50:09,076 [Thread-27] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:85 - HTTP request: GET /eID-Client?cardType=https%3A%2F%2Fwww.d-trust.net%2Fprodukte%2Fd-trust-signaturkarten%2Fd-trust-card%2Fstandard_v3&tcTokenURL=https%3A%2F%2Fservice.skidentity.de%2Fbackend%2Ftls%2Factivate-client%3Fsession%3D4DF741A9C9CA2274462DF4BA25577512%26type%3Dhttps%3A%2F%2Fwww.d-trust.net%2Fprodukte%2Fd-trust-signaturkarten%2Fd-trust-card%2Fstandard_v3%26protocol%3Durn%3Aoid%3A1.3.162.15480.3.0.25%26activation-type%3DeID-Client [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:35.0) Gecko/20100101 Firefox/35.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en;q=0.7,en-us;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://service.skidentity.de/ids/index.html, Connection: keep-alive]
529
2015-03-07 15:50:09,078 [Thread-27] INFO  o.o.binding.tctoken.ResourceContext:198 - Trying to load resource from: https://service.skidentity.de/backend/tls/activate-client?session=4DF741A9C9CA2274462DF4BA25577512&type=https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3&protocol=urn:oid:1.3.162.15480.3.0.25&activation-type=eID-Client
530
2015-03-07 15:50:09,079 [Thread-27] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
531
2015-03-07 15:50:09,079 [Thread-27] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
532
2015-03-07 15:50:09,116 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:231 - Performing TLS handshake.
533
2015-03-07 15:50:09,181 [Thread-27] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
534
2015-03-07 15:50:09,302 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:233 - TLS handshake performed.
535
2015-03-07 15:50:09,303 [Thread-27] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
536
  GET /backend/tls/activate-client?session=4DF741A9C9CA2274462DF4BA25577512&type=https://www.d-trust.net/produkte/d-trust-signaturkarten/d-trust-card/standard_v3&protocol=urn:oid:1.3.162.15480.3.0.25&activation-type=eID-Client HTTP/1.1
537
  Connection: keep-alive
538
  User-Agent: Open-eCard-App/1.1.0-rc8
539
  Host: service.skidentity.de
540
  Accept: text/xml, */*;q=0.8
541
  Accept-Charset: utf-8, *;q=0.8
542

    
543
2015-03-07 15:50:09,304 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:254 - Sending HTTP request.
544
2015-03-07 15:50:09,354 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:256 - HTTP response received.
545
2015-03-07 15:50:09,355 [Thread-27] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
546
  HTTP/1.1 200 OK
547
  Date: Sat, 07 Mar 2015 14:50:12 GMT
548
  Server: Apache/2.4.10 (Debian)
549
  Vary: Accept-Encoding
550
  Access-Control-Allow-Headers: accept, content-type
551
  Access-Control-Allow-Origin: http://service.skidentity.de
552
  Access-Control-Allow-Methods: GET, POST, OPTIONS
553
  Access-Control-Allow-Credentials: true
554
  Content-Length: 595
555
  Keep-Alive: timeout=5, max=100
556
  Connection: Keep-Alive
557
  Content-Type: text/xml;charset=UTF-8
558

    
559

    
560
2015-03-07 15:50:09,357 [Thread-27] DEBUG o.o.binding.tctoken.TCTokenContext:91 - Cleaned up TCToken:
561
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
562
<TCTokenType>
563
  <ServerAddress>https://service.skidentity.de:443/backend/tls/cert-authenticate?session=4DF741A9C9CA2274462DF4BA25577512&amp;client-type=eID-Client</ServerAddress>
564
  <SessionIdentifier>4DF741A9C9CA2274462DF4BA25577512</SessionIdentifier>
565
  <RefreshAddress>https://service.skidentity.de:443/backend/tls/terminate-client?session=4DF741A9C9CA2274462DF4BA25577512&amp;client-type=eID-Client</RefreshAddress>
566
  <Binding>urn:ietf:rfc:2616</Binding>
567
  <PathSecurity-Protocol>urn:ietf:rfc:4346</PathSecurity-Protocol>
568
</TCTokenType>
569

    
570
2015-03-07 15:50:09,363 [Thread-27] WARN  o.o.binding.tctoken.TCTokenHandler:289 - Checks according to BSI TR03112 3.4.2, 3.4.4 (TCToken specific) and 3.4.5 are disabled.
571
2015-03-07 15:50:09,405 [Thread-27] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationPath
572
2015-03-07 15:50:09,405 [Thread-27] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
573
2015-03-07 15:50:09,405 [Thread-27] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
574
2015-03-07 15:50:09,410 [Thread-27] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
575
2015-03-07 15:50:09,410 [Thread-27] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x07 0xD2 0x76 0x00 0x01 0x44 0x80 0x00 
576
2015-03-07 15:50:09,430 [Thread-27] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
577
2015-03-07 15:50:09,431 [TLS Auth] INFO  o.o.c.t.ClientCertDefaultTlsClient:215 - TLS warning sent.
578
2015-03-07 15:50:09,432 [TLS Auth] INFO  o.o.c.t.ClientCertDefaultTlsClient:216 - TLS(WARN): Close [close_notify=0] --> Connection closed
579
2015-03-07 15:50:09,433 [TLS Auth] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
580
2015-03-07 15:50:09,433 [TLS Auth] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
581
2015-03-07 15:50:09,534 [TLS Auth] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
582
2015-03-07 15:50:09,663 [TLS Auth] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
583
  GET /backend/tls/cert-authenticate?session=4DF741A9C9CA2274462DF4BA25577512&client-type=eID-Client HTTP/1.1
584
  Connection: keep-alive
585
  User-Agent: Open-eCard-App/1.1.0-rc8
586
  Host: service.skidentity.de:443
587
  Accept: text/html; */*
588

    
589
2015-03-07 15:50:09,769 [TLS Auth] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
590
2015-03-07 15:50:09,788 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationList
591
2015-03-07 15:50:09,789 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDList
592
2015-03-07 15:50:09,789 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDList
593
2015-03-07 15:50:09,789 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDGet
594
2015-03-07 15:50:09,789 [TLS Auth] DEBUG o.o.c.c.s.GenericCryptoSignerFinder:236 - DTRUSTAuthenticationKeyPKCS1 is usable for TLSv1.1 and TLS1.2 signatures.
595
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationList
596
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
597
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
598
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
599
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetList
600
2015-03-07 15:50:09,790 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
601
2015-03-07 15:50:09,791 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
602
2015-03-07 15:50:09,793 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
603
2015-03-07 15:50:09,793 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0C 0xA0 0x00 0x00 0x02 0x44 0x46 0x5F 0x43 0x65 0x72 0x74 0x73 
604
2015-03-07 15:50:09,828 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x90 0x00 
605
2015-03-07 15:50:09,830 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.ACLList
606
2015-03-07 15:50:09,831 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DataSetSelect
607
2015-03-07 15:50:09,831 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
608
2015-03-07 15:50:09,834 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xA4 0x02 0x04 0x02 0xC1 0x00 0xFF 
609
2015-03-07 15:50:09,858 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x62 0x1A 0x80 0x02 0x0E 0x00 0xC5 0x02 0x05 0x7D 0x82 0x01 0x29 0x83 0x02 0xC1 0x00 0x88 0x01 0x80 0x8A 0x01 0x05 0xA1 0x03 0x8B 0x01 0x02 0x90 0x00 
610
2015-03-07 15:50:09,859 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DSIRead
611
2015-03-07 15:50:09,860 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
612
2015-03-07 15:50:09,861 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
613
2015-03-07 15:50:09,911 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x82 0x05 0x79 0x30 0x82 0x04 0x61 0xA0 0x03 0x02 0x01 0x02 0x02 0x03 0x0F 0xF3 0x5E 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x05 0x05 0x00 0x30 0x4E 0x31 0x0B 0x30 0x09 0x06 0x03 0x55 0x04 0x06 0x13 0x02 0x44 0x45 0x31 0x15 0x30 0x13 0x06 0x03 0x55 0x04 0x0A 0x0C 0x0C 0x44 0x2D 0x54 0x72 0x75 0x73 0x74 0x20 0x47 0x6D 0x62 0x48 0x31 0x28 0x30 0x26 0x06 0x03 0x55 0x04 0x03 0x0C 0x1F 0x54 0x65 0x73 0x74 0x20 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x20 0x41 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x20 0x32 0x30 0x31 0x31 0x20 0x43 0x41 0x20 0x31 0x30 0x1E 0x17 0x0D 0x31 0x33 0x31 0x30 0x33 0x30 0x30 0x36 0x30 0x32 0x34 0x39 0x5A 0x17 0x0D 0x31 0x34 0x31 0x30 0x33 0x30 0x30 0x36 0x30 0x32 0x34 0x39 0x5A 0x30 0x81 0xB0 0x31 0x0B 0x30 0x09 0x06 0x03 0x55 0x04 0x06 0x13 0x02 0x44 0x45 0x31 0x13 0x30 0x11 0x06 0x03 0x55 0x04 0x0A 0x0C 0x0A 0x65 0x63 0x73 0x65 0x63 0x20 0x47 0x6D 0x62 0x48 0x31 0x23 0x30 0x21 0x06 0x03 0x55 0x04 0x0B 0x0C 0x1A 0x46 0x6F 0x72 0x20 0x74 0x65 0x73 0x74 0x69 0x6E 0x67 0x20 0x70 0x75 0x72 0x70 0x6F 0x73 0x65 0x73 0x20 0x6F 0x6E 0x6C 0x79 0x21 0x31 0x19 0x30 0x17 0x06 0x03 0x55 0x04 0x03 0x0C 0x10 0x44 0x65 0x74 0x6C 0x65 0x66 0x20 0x48 0xC3 0xBC 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x31 0x19 0x30 0x17 0x06 0x03 0x55 0x04 0x2A 0x90 0x00 
614
2015-03-07 15:50:09,912 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
615
2015-03-07 15:50:09,912 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x00 0xFF 0xFF 
616
2015-03-07 15:50:09,954 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x0C 0x10 0x44 0x65 0x74 0x6C 0x65 0x66 0x20 0x57 0x69 0x6C 0x6C 0x69 0x62 0x61 0x6C 0x64 0x31 0x12 0x30 0x10 0x06 0x03 0x55 0x04 0x04 0x0C 0x09 0x48 0xC3 0xBC 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x31 0x1D 0x30 0x1B 0x06 0x03 0x55 0x04 0x05 0x13 0x14 0x44 0x54 0x52 0x57 0x4D 0x33 0x38 0x32 0x38 0x36 0x30 0x36 0x30 0x33 0x38 0x37 0x30 0x39 0x31 0x31 0x30 0x82 0x01 0x22 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x01 0x05 0x00 0x03 0x82 0x01 0x0F 0x00 0x30 0x82 0x01 0x0A 0x02 0x82 0x01 0x01 0x00 0xAC 0x88 0xDF 0xDF 0xF6 0xD1 0x59 0x41 0x28 0x57 0x66 0x1E 0xDD 0xDF 0x51 0x5C 0x29 0xC8 0xC5 0xE8 0x90 0x3E 0xD6 0x0D 0x05 0xEB 0x67 0xEA 0x90 0xC4 0x84 0x51 0xA9 0x7F 0xB0 0x52 0xB8 0x77 0x00 0xC5 0x75 0xAD 0x22 0x33 0xE0 0x14 0x92 0xDF 0x27 0xF9 0xCA 0xA1 0x57 0x1B 0xCF 0x88 0x24 0xE2 0x38 0xC5 0x5F 0x32 0xC2 0xA8 0xA3 0x31 0xAE 0xED 0x55 0xFE 0x96 0xC1 0xB1 0x11 0x35 0xBA 0x74 0x83 0xC2 0x83 0xC5 0xCD 0x67 0xE8 0xCA 0x28 0xD4 0xEE 0x17 0xD5 0xF7 0xD7 0xAB 0xDE 0x92 0x84 0xE8 0xFB 0xE1 0xC0 0x20 0xAB 0xEA 0xF7 0x74 0xA6 0xCB 0x59 0x8A 0x26 0x5D 0x37 0xC4 0x70 0x7C 0xBF 0x39 0x14 0x93 0x14 0x78 0xC5 0x28 0xD6 0xEA 0x26 0x12 0x6C 0xE3 0x6D 0xD5 0xB1 0xC6 0x9D 0x2D 0x34 0x68 0x2B 0xB7 0x13 0xA7 0x28 0x3E 0x4B 0xC8 0xDB 0x0F 0xBC 0x2B 0xEA 0x0D 0x0A 0xF5 0x90 0x00 
617
2015-03-07 15:50:09,955 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
618
2015-03-07 15:50:09,956 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x01 0xFE 0xFF 
619
2015-03-07 15:50:09,997 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0xCF 0xAF 0x25 0xF3 0x01 0x9E 0x36 0xE3 0x15 0x50 0x66 0x57 0x26 0x8A 0x8F 0x9C 0xDF 0x28 0x48 0xA5 0x80 0x83 0x56 0xB6 0x2D 0x64 0xC4 0xC0 0xA8 0x4F 0x91 0x0D 0xA2 0x8B 0x8C 0xFD 0xB3 0x7E 0xED 0x2D 0x47 0xAF 0x56 0xCF 0x34 0xD2 0xA1 0xB9 0x54 0x58 0x93 0x5A 0x00 0xA5 0x11 0x4F 0x82 0x90 0xAF 0xE5 0x05 0xC5 0xC4 0xE3 0xC5 0x70 0xEB 0x32 0xAC 0xFE 0x50 0xE7 0xF7 0x39 0x4E 0xF7 0x9F 0x60 0x71 0xAF 0x5C 0x38 0x9D 0x97 0xBE 0xD1 0x82 0x22 0x2B 0xDC 0x39 0xDF 0x57 0xE7 0x4B 0x1D 0xF5 0xC5 0x63 0x27 0xE3 0x83 0xF5 0x02 0x03 0x01 0x00 0x01 0xA3 0x82 0x01 0xFB 0x30 0x82 0x01 0xF7 0x30 0x23 0x06 0x03 0x55 0x1D 0x25 0x04 0x1C 0x30 0x1A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x03 0x02 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x03 0x04 0x06 0x04 0x55 0x1D 0x25 0x00 0x30 0x1F 0x06 0x03 0x55 0x1D 0x23 0x04 0x18 0x30 0x16 0x80 0x14 0xE8 0xF4 0x3F 0x58 0xB1 0x2D 0x07 0x85 0x08 0x80 0x01 0xED 0xB0 0x32 0xA9 0x28 0x3F 0x8A 0xEB 0x83 0x30 0x3A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x01 0x01 0x04 0x2E 0x30 0x2C 0x30 0x2A 0x06 0x08 0x2B 0x06 0x01 0x05 0x05 0x07 0x30 0x01 0x86 0x1E 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x6F 0x63 0x73 0x70 0x2D 0x73 0x68 0x61 0x32 0x35 0x36 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x30 0x17 0x06 0x03 0x55 0x1D 0x20 0x04 0x10 0x90 0x00 
620
2015-03-07 15:50:09,998 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
621
2015-03-07 15:50:09,999 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x02 0xFD 0xFF 
622
2015-03-07 15:50:10,040 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x30 0x0E 0x30 0x0C 0x06 0x0A 0x2B 0x06 0x01 0x04 0x01 0xA5 0x34 0x02 0x02 0x02 0x30 0x81 0xCF 0x06 0x03 0x55 0x1D 0x1F 0x04 0x81 0xC7 0x30 0x81 0xC4 0x30 0x81 0xC1 0xA0 0x81 0xBE 0xA0 0x81 0xBB 0x86 0x79 0x6C 0x64 0x61 0x70 0x3A 0x2F 0x2F 0x64 0x69 0x72 0x65 0x63 0x74 0x6F 0x72 0x79 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x2F 0x43 0x4E 0x3D 0x54 0x65 0x73 0x74 0x25 0x32 0x30 0x44 0x2D 0x54 0x52 0x55 0x53 0x54 0x25 0x32 0x30 0x41 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x25 0x32 0x30 0x32 0x30 0x31 0x31 0x25 0x32 0x30 0x43 0x41 0x25 0x32 0x30 0x31 0x2C 0x4F 0x3D 0x44 0x2D 0x54 0x72 0x75 0x73 0x74 0x25 0x32 0x30 0x47 0x6D 0x62 0x48 0x2C 0x43 0x3D 0x44 0x45 0x3F 0x63 0x65 0x72 0x74 0x69 0x66 0x69 0x63 0x61 0x74 0x65 0x72 0x65 0x76 0x6F 0x63 0x61 0x74 0x69 0x6F 0x6E 0x6C 0x69 0x73 0x74 0x86 0x3E 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x77 0x77 0x77 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x2F 0x63 0x72 0x6C 0x2F 0x74 0x65 0x73 0x74 0x5F 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x5F 0x61 0x64 0x76 0x61 0x6E 0x63 0x65 0x64 0x5F 0x32 0x30 0x31 0x31 0x5F 0x63 0x61 0x5F 0x31 0x2E 0x63 0x72 0x6C 0x30 0x33 0x06 0x03 0x55 0x1D 0x12 0x04 0x2C 0x30 0x2A 0x81 0x10 0x69 0x6E 0x66 0x6F 0x40 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x90 0x00 
623
2015-03-07 15:50:10,041 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
624
2015-03-07 15:50:10,041 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x03 0xFC 0xFF 
625
2015-03-07 15:50:10,082 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x86 0x16 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x77 0x77 0x77 0x2E 0x64 0x2D 0x74 0x72 0x75 0x73 0x74 0x2E 0x6E 0x65 0x74 0x30 0x1D 0x06 0x03 0x55 0x1D 0x0E 0x04 0x16 0x04 0x14 0x2C 0xB2 0x1D 0x88 0x15 0x63 0x83 0xCB 0xDB 0xA1 0x51 0x98 0x48 0x90 0x9C 0x90 0xFF 0x69 0x9E 0x75 0x30 0x0E 0x06 0x03 0x55 0x1D 0x0F 0x01 0x01 0xFF 0x04 0x04 0x03 0x02 0x04 0xB0 0x30 0x24 0x06 0x03 0x55 0x1D 0x11 0x04 0x1D 0x30 0x1B 0x81 0x19 0x64 0x65 0x74 0x6C 0x65 0x66 0x2E 0x68 0x75 0x65 0x68 0x6E 0x6C 0x65 0x69 0x6E 0x40 0x65 0x63 0x73 0x65 0x63 0x2E 0x64 0x65 0x30 0x0D 0x06 0x09 0x2A 0x86 0x48 0x86 0xF7 0x0D 0x01 0x01 0x05 0x05 0x00 0x03 0x82 0x01 0x01 0x00 0x0F 0xD0 0xF9 0x63 0x86 0x88 0x5B 0x72 0xC3 0x18 0x16 0x60 0x8B 0xF8 0xF8 0xFF 0x79 0x64 0xAF 0x1B 0x2B 0x5C 0x33 0x00 0x7B 0xD2 0x35 0xCC 0x5A 0xBC 0x45 0x7D 0xDB 0xD3 0x46 0x62 0x77 0x7C 0x54 0xD6 0x2C 0x4B 0x30 0x22 0x94 0x29 0x17 0x12 0x66 0x36 0x41 0xF4 0x55 0x49 0xFA 0x76 0xA3 0xA2 0x93 0xD1 0x82 0x52 0xA8 0x57 0x64 0xA4 0x10 0x06 0x88 0x2E 0x15 0xB7 0x78 0x3E 0xF2 0xC0 0x75 0x47 0xCC 0xE0 0x1F 0x1C 0x62 0x71 0xF1 0x22 0x08 0x23 0x1E 0x9B 0x61 0x6E 0x8B 0xF3 0x22 0xF8 0x04 0xA6 0x41 0xA6 0x2B 0x46 0x2A 0x78 0x36 0xCE 0x37 0x81 0xDB 0xCE 0x6C 0x6B 0xF0 0xE9 0x7E 0x1E 0x63 0x51 0xC1 0x31 0x76 0xAD 0x31 0x56 0x46 0xB2 0x90 0x00 
626
2015-03-07 15:50:10,083 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
627
2015-03-07 15:50:10,083 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:221 - Send APDU: 0x00 0xB0 0x04 0xFB 0xFF 
628
2015-03-07 15:50:10,107 [TLS Auth] DEBUG o.o.i.s.wrapper.SingleThreadChannel:224 - Receive APDU: 0x8F 0xE2 0x2F 0x60 0xBF 0x88 0x56 0x88 0xCA 0x56 0x16 0xD7 0x84 0x6F 0x28 0xB5 0xEC 0x75 0xD6 0x92 0x38 0x1E 0x72 0x8B 0x36 0xFB 0x70 0xCA 0x9A 0xAF 0x2B 0xE9 0x32 0x9C 0xEC 0x3D 0x3C 0x34 0xCD 0x7D 0x92 0x2D 0x36 0x0E 0x1A 0x59 0x77 0xE4 0x95 0x4E 0x2E 0xEC 0xF0 0xEA 0x5B 0xA9 0xC9 0x77 0xC5 0xF1 0x31 0x13 0xE5 0xF6 0x59 0xDC 0xBB 0xBD 0xC4 0x55 0x36 0x88 0x51 0x4F 0x63 0xFF 0x90 0x45 0xB5 0x86 0x4E 0xD3 0x8A 0x3E 0x3C 0x28 0xCD 0x03 0x71 0x8C 0xFE 0x6F 0x74 0xD8 0x72 0x4E 0x74 0xBA 0x4A 0xDB 0xA3 0x44 0x28 0xC7 0x8B 0xAE 0xA1 0xA0 0xA2 0xE9 0xE0 0xDF 0x3B 0xCE 0x3C 0xB5 0x7A 0xA5 0xFB 0xB8 0x53 0x93 0x63 0x9F 0xE6 0x40 0xD6 0xCD 0xAF 0x0F 0x62 0x82 
629
2015-03-07 15:50:10,109 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.ACLList
630
2015-03-07 15:50:10,111 [TLS Auth] ERROR o.o.c.c.s.GenericCryptoSignerFinder:201 - Searching for DID failed
631
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/sal#namedEntityNotFound
632
  ==> The given DataSet cannot be found.
633
	at org.openecard.common.ECardException.makeException(ECardException.java:64) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
634
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
635
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
636
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.filterTLSCapableDIDs(GenericCryptoSignerFinder.java:294) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
637
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.findDID(GenericCryptoSignerFinder.java:189) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
638
	at org.openecard.crypto.common.sal.GenericCryptoSignerFinder.findFirstMatching(GenericCryptoSignerFinder.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
639
	at org.openecard.crypto.tls.auth.SmartCardCredentialFactory.getClientCredentials(SmartCardCredentialFactory.java:61) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
640
	at org.openecard.crypto.tls.auth.DynamicAuthentication.getClientCredentials(DynamicAuthentication.java:173) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
641
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:363) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
642
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
643
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
644
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
645
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
646
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
647
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
648
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
649
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
650
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
651
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
652
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
653
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
654
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
655
	at java.io.BufferedInputStream.read1(Unknown Source) [na:1.8.0_31]
656
	at java.io.BufferedInputStream.read(Unknown Source) [na:1.8.0_31]
657
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
658
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
659
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
660
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
661
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
662
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
663
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
664
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
665
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
666
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
667
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
668
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
669
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
670
2015-03-07 15:50:10,111 [TLS Auth] ERROR o.o.c.t.a.SmartCardCredentialFactory:65 - No suitable credential found. Returning empty list.
671
2015-03-07 15:50:10,112 [TLS Auth] ERROR o.o.c.t.ClientCertDefaultTlsClient:218 - TLS error sent.
672
2015-03-07 15:50:10,113 [TLS Auth] ERROR o.o.c.t.ClientCertDefaultTlsClient:219 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
673
org.openecard.bouncycastle.crypto.tls.TlsFatalAlert: null
674
	at org.openecard.bouncycastle.crypto.tls.TlsECDHEKeyExchange.processClientCredentials(TlsECDHEKeyExchange.java:210) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
675
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:379) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
676
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
677
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
678
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
679
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
680
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
681
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
682
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
683
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
684
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
685
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
686
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
687
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
688
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
689
	at java.io.BufferedInputStream.read1(Unknown Source) [na:1.8.0_31]
690
	at java.io.BufferedInputStream.read(Unknown Source) [na:1.8.0_31]
691
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
692
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
693
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
694
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
695
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
696
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
697
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
698
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
699
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
700
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
701
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
702
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
703
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
704
2015-03-07 15:50:10,114 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationDisconnect
705
2015-03-07 15:50:10,115 [TLS Auth] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
706
2015-03-07 15:50:10,117 [Thread-27] ERROR o.o.binding.tctoken.TCTokenHandler:413 - java.io.IOException: Internal TLS error, this could be an attack
707
java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
708
	at java.util.concurrent.FutureTask.report(Unknown Source) ~[na:1.8.0_31]
709
	at java.util.concurrent.FutureTask.get(Unknown Source) ~[na:1.8.0_31]
710
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:408) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
711
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:222) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
712
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:336) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
713
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:94) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
714
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
715
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:111) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
716
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
717
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
718
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
719
Caused by: java.io.IOException: Internal TLS error, this could be an attack
720
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.failWithError(TlsProtocol.java:655) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
721
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:475) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
722
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
723
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
724
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
725
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
726
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
727
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
728
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
729
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
730
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
731
	at java.io.BufferedInputStream.read1(Unknown Source) ~[na:1.8.0_31]
732
	at java.io.BufferedInputStream.read(Unknown Source) ~[na:1.8.0_31]
733
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
734
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
735
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
736
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
737
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
738
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
739
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
740
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
741
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
742
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
743
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
744
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_31]
745
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_31]
746
2015-03-07 15:50:10,119 [Thread-27] ERROR o.o.binding.tctoken.TCTokenHandler:349 - java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
747
org.openecard.transport.paos.PAOSException: java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
748
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:420) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
749
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:222) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
750
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:336) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
751
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:94) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
752
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
753
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:111) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
754
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
755
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
756
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
757
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: Internal TLS error, this could be an attack
758
	at java.util.concurrent.FutureTask.report(Unknown Source) ~[na:1.8.0_31]
759
	at java.util.concurrent.FutureTask.get(Unknown Source) ~[na:1.8.0_31]
760
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:408) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
761
	... 8 common frames omitted
762
Caused by: java.io.IOException: Internal TLS error, this could be an attack
763
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.failWithError(TlsProtocol.java:655) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
764
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:475) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
765
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
766
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
767
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.handleHandshakeMessage(TlsClientProtocol.java:570) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
768
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processHandshake(TlsProtocol.java:306) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
769
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.processRecord(TlsProtocol.java:229) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
770
	at org.openecard.bouncycastle.crypto.tls.RecordStream.readRecord(RecordStream.java:172) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
771
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:464) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
772
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.readApplicationData(TlsProtocol.java:451) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
773
	at org.openecard.bouncycastle.crypto.tls.TlsInputStream.read(TlsInputStream.java:29) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
774
	at java.io.BufferedInputStream.read1(Unknown Source) ~[na:1.8.0_31]
775
	at java.io.BufferedInputStream.read(Unknown Source) ~[na:1.8.0_31]
776
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
777
	at org.openecard.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
778
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:131) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
779
	at org.openecard.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:54) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
780
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
781
	at org.openecard.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
782
	at org.openecard.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
783
	at org.openecard.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
784
	at org.openecard.binding.tctoken.HttpGetTask.getRequest(HttpGetTask.java:110) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
785
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:73) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
786
	at org.openecard.binding.tctoken.HttpGetTask.call(HttpGetTask.java:56) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
787
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_31]
788
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_31]
789
2015-03-07 15:50:10,120 [Thread-27] INFO  o.o.binding.tctoken.ResourceContext:198 - Trying to load resource from: https://service.skidentity.de:443/backend/tls/terminate-client?session=4DF741A9C9CA2274462DF4BA25577512&client-type=eID-Client
790
2015-03-07 15:50:10,120 [Thread-27] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
791
2015-03-07 15:50:10,120 [Thread-27] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
792
2015-03-07 15:50:10,157 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:231 - Performing TLS handshake.
793
2015-03-07 15:50:10,223 [Thread-27] DEBUG o.o.c.tls.verify.HostnameVerifier:112 - Comparing connection hostname against certificate hostname: [service.skidentity.de] [service.skidentity.de]
794
2015-03-07 15:50:10,349 [Thread-27] DEBUG o.o.binding.tctoken.ResourceContext:233 - TLS handshake performed.
795
2015-03-07 15:50:10,350 [Thread-27] INFO  o.o.c.t.ClientCertDefaultTlsClient:215 - TLS warning sent.
796
2015-03-07 15:50:10,351 [Thread-27] INFO  o.o.c.t.ClientCertDefaultTlsClient:216 - TLS(WARN): Close [close_notify=0] --> Connection closed
797
2015-03-07 15:50:10,351 [Thread-27] DEBUG o.o.binding.tctoken.TCTokenHandler:469 - Setting redirect address to 'https://service.skidentity.de:443/backend/tls/terminate-client?session=4DF741A9C9CA2274462DF4BA25577512&client-type=eID-Client'.
798
2015-03-07 15:50:10,352 [Thread-27] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:153 - Recieved BindingResult with ResultCode REDIRECT
799
2015-03-07 15:50:10,352 [Thread-27] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:142 - BindingResult contains no body.
800
2015-03-07 15:50:10,353 [Thread-27] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:115 - HTTP response: HTTP/1.1 303  [Location: https://service.skidentity.de:443/backend/tls/terminate-client?client-type=eID-Client&ResultMinor=incorrectParameter&ResultMajor=error&session=4DF741A9C9CA2274462DF4BA25577512] [Content-Type: text/plain; charset=UTF-8,Content-Length: 64,Chunked: false]
801
2015-03-07 15:50:26,463 [AWT-EventQueue-2] DEBUG o.openecard.richclient.gui.Status$1:161 - Shutdown button pressed.
802
2015-03-07 15:50:26,490 [Open-eCard Localhost-Binding] ERROR o.o.c.binding.http.HTTPService:138 - socket closed
803
java.net.SocketException: socket closed
804
	at java.net.DualStackPlainSocketImpl.accept0(Native Method) ~[na:1.8.0_31]
805
	at java.net.DualStackPlainSocketImpl.socketAccept(Unknown Source) ~[na:1.8.0_31]
806
	at java.net.AbstractPlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_31]
807
	at java.net.PlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_31]
808
	at java.net.ServerSocket.implAccept(Unknown Source) ~[na:1.8.0_31]
809
	at java.net.ServerSocket.accept(Unknown Source) ~[na:1.8.0_31]
810
	at org.openecard.control.binding.http.HTTPService.run(HTTPService.java:117) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
811
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
812
2015-03-07 15:50:26,491 [pool-2-thread-1] WARN  org.openecard.ifd.scio.IFD:514 - Wait interrupted by another thread.
813
java.lang.InterruptedException: null
814
	at java.util.concurrent.FutureTask.awaitDone(Unknown Source) [na:1.8.0_31]
815
	at java.util.concurrent.FutureTask.get(Unknown Source) [na:1.8.0_31]
816
	at org.openecard.ifd.scio.IFD.wait(IFD.java:485) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
817
	at org.openecard.event.EventManager.wait(EventManager.java:118) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
818
	at org.openecard.event.EventRunner.run(EventRunner.java:73) [richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
819
	at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [na:1.8.0_31]
820
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
821
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_31]
822
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_31]
823
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
824
2015-03-07 15:50:26,495 [pool-2-thread-1] WARN  org.openecard.event.EventRunner:77 - IFD Wait returned with error.
825
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
826
  ==> Wait interrupted by another thread.
827
	at org.openecard.common.ECardException.makeException(ECardException.java:64) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
828
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:47) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
829
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:69) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
830
	at org.openecard.event.EventManager.wait(EventManager.java:120) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
831
	at org.openecard.event.EventRunner.run(EventRunner.java:73) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
832
	at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [na:1.8.0_31]
833
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
834
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_31]
835
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_31]
836
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
837
2015-03-07 15:50:26,497 [pool-2-thread-1] INFO  org.openecard.event.EventRunner:85 - Event thread interrupted.
838
java.lang.InterruptedException: sleep interrupted
839
	at java.lang.Thread.sleep(Native Method) [na:1.8.0_31]
840
	at org.openecard.event.EventRunner.run(EventRunner.java:80) ~[richclient-1.1.0-rc8-20150307-1409.jar.pack.gz:na]
841
	at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [na:1.8.0_31]
842
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_31]
843
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_31]
844
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_31]
845
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_31]
(4-4/8)