Project

General

Profile

Bug #803 » richclient_info.log

Michele Adduci, 04/20/2020 03:38 PM

 
1
2020-04-20 15:24:40,047+02 [Addon-File-Watcher] ERROR o.o.addon.AddonFileSystemMonitor:170 - The jar file conf does not seem to be an add-on.
2
2020-04-20 15:25:08,793+02 [AWT-EventQueue-0] INFO  o.o.richclient.LogbackConfig:81 - Configured Logback with config file from: /home/user/.openecard/richclient_logback.xml
3
2020-04-20 15:25:16,711+02 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
4
2020-04-20 15:25:16,715+02 [FuturePromise-4] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
5
2020-04-20 15:25:16,718+02 [Thread-5] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
6
2020-04-20 15:25:16,718+02 [Thread-5] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
7
2020-04-20 15:25:16,766+02 [Thread-5] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
8
2020-04-20 15:25:16,777+02 [Thread-5] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
9
2020-04-20 15:25:16,808+02 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
10
2020-04-20 15:25:16,809+02 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
11
2020-04-20 15:25:16,820+02 [Thread-5] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
12
2020-04-20 15:25:17,023+02 [Thread-5] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
13
2020-04-20 15:25:17,025+02 [Thread-5] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
14
2020-04-20 15:25:17,255+02 [Thread-5] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
15
2020-04-20 15:25:17,259+02 [Thread-5] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
16
  GET /tctoken HTTP/1.1
17
  Connection: keep-alive
18
  User-Agent: Open eCard App/1.4.1
19
  Host: eservice-idp-test.secunet.de:443
20
  Accept: text/xml, */*;q=0.8
21
  Accept-Charset: utf-8, *;q=0.8
22

    
23
2020-04-20 15:25:17,260+02 [Thread-5] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
24
2020-04-20 15:25:17,270+02 [Thread-5] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
25
2020-04-20 15:25:17,286+02 [Thread-5] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
26
java.net.SocketException: Connection reset
27
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
28
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
29
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
30
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
31
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
32
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
33
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
34
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
35
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
36
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
37
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
38
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
39
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
40
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
41
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
42
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
43
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
44
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
45
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
46
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
47
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
48
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
49
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
50
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
51
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
52
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
53
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
54
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
55
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
56
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
57
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
58
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
59
2020-04-20 15:25:17,291+02 [Thread-5] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
60
2020-04-20 15:25:17,291+02 [Thread-5] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
61
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
62
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
63
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
64
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
65
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
66
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
67
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
68
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
69
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
70
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
71
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
72
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
73
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
74
Caused by: java.net.SocketException: Connection reset
75
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
76
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
77
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
78
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
79
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
80
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
81
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
82
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
83
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
84
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
85
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
86
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
87
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
88
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
89
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
90
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
91
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
92
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
93
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
94
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
95
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
96
	... 11 common frames omitted
97
2020-04-20 15:25:17,292+02 [Thread-5] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
98
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
99

    
100
2020-04-20 15:25:17,292+02 [Thread-5] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
101
2020-04-20 15:25:17,292+02 [Thread-5] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
102
2020-04-20 15:25:17,293+02 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
103
2020-04-20 15:25:17,293+02 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
104
2020-04-20 15:25:17,306+02 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
105
2020-04-20 15:25:17,308+02 [Thread-5] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
106
2020-04-20 15:25:28,451+02 [Thread-6] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
107
2020-04-20 15:25:28,452+02 [Thread-6] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
108
2020-04-20 15:25:28,452+02 [FuturePromise-5] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
109
2020-04-20 15:25:28,453+02 [Thread-6] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
110
2020-04-20 15:25:28,500+02 [Thread-6] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
111
2020-04-20 15:25:28,501+02 [Thread-6] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
112
2020-04-20 15:25:28,501+02 [Thread-6] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for java. Trying next one.
113
2020-04-20 15:25:28,501+02 [Thread-6] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Firefox settings folder not found!
114
2020-04-20 15:25:28,502+02 [Thread-6] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Strategy {0} failed trying next one.
115
com.github.markusbernhardt.proxy.util.ProxyException: No Firefox installation found
116
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.readSettings(FirefoxProxySearchStrategy.java:174)
117
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.getProxySelector(FirefoxProxySearchStrategy.java:106)
118
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.ProxySearch.getProxySelector(ProxySearch.java:216)
119
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettingsLoader$1.find(ProxySettingsLoader.java:169)
120
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.loadSelector(UpdatingProxySelector.java:57)
121
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.select(UpdatingProxySelector.java:67)
122
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getProxy(ProxySettings.java:123)
123
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getSocket(ProxySettings.java:149)
124
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:150)
125
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
126
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
127
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
128
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
129
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
130
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
131
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
132
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
133
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
134
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
135
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
136
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
137
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
138
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
139
2020-04-20 15:25:28,502+02 [Thread-6] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for desktop. Trying next one.
140
2020-04-20 15:25:28,502+02 [Thread-6] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for env. Trying next one.
141
2020-04-20 15:25:28,502+02 [Thread-6] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
142
2020-04-20 15:25:28,503+02 [Thread-6] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
143
2020-04-20 15:25:28,503+02 [Thread-6] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
144
2020-04-20 15:25:28,533+02 [Thread-6] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_3_A]
145
2020-04-20 15:25:28,533+02 [Thread-6] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
146
2020-04-20 15:25:28,612+02 [Thread-6] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
147
2020-04-20 15:25:28,612+02 [Thread-6] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
148
  GET /tctoken HTTP/1.1
149
  Connection: keep-alive
150
  User-Agent: Open eCard App/1.4.1
151
  Host: eservice-idp-test.secunet.de:443
152
  Accept: text/xml, */*;q=0.8
153
  Accept-Charset: utf-8, *;q=0.8
154

    
155
2020-04-20 15:25:28,612+02 [Thread-6] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
156
2020-04-20 15:25:28,616+02 [Thread-6] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
157
2020-04-20 15:25:28,616+02 [Thread-6] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
158
java.net.SocketException: Connection reset
159
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
160
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
161
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
162
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
163
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
164
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
165
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
166
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
167
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
168
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
169
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
170
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
171
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
172
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
173
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
174
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
175
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
176
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
177
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
178
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
179
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
180
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
181
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
182
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
183
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
184
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
185
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
186
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
187
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
188
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
189
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
190
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
191
2020-04-20 15:25:28,617+02 [Thread-6] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
192
2020-04-20 15:25:28,617+02 [Thread-6] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
193
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
194
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
195
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
196
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
197
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
198
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
199
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
200
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
201
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
202
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
203
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
204
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
205
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
206
Caused by: java.net.SocketException: Connection reset
207
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
208
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
209
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
210
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
211
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
212
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
213
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
214
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
215
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
216
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
217
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
218
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
219
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
220
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
221
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
222
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
223
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
224
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
225
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
226
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
227
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
228
	... 11 common frames omitted
229
2020-04-20 15:25:28,618+02 [Thread-6] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
230
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
231

    
232
2020-04-20 15:25:28,618+02 [Thread-6] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
233
2020-04-20 15:25:28,618+02 [Thread-6] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
234
2020-04-20 15:25:28,618+02 [Thread-6] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
235
2020-04-20 15:25:28,619+02 [Thread-6] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
236
2020-04-20 15:25:28,619+02 [Thread-6] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
237
2020-04-20 15:25:28,620+02 [Thread-6] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
238
2020-04-20 15:25:40,690+02 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
239
2020-04-20 15:25:40,691+02 [Thread-7] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
240
2020-04-20 15:25:40,691+02 [FuturePromise-6] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
241
2020-04-20 15:25:40,692+02 [Thread-7] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
242
2020-04-20 15:25:40,734+02 [Thread-7] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
243
2020-04-20 15:25:40,735+02 [Thread-7] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
244
2020-04-20 15:25:40,735+02 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
245
2020-04-20 15:25:40,735+02 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
246
2020-04-20 15:25:40,736+02 [Thread-7] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
247
2020-04-20 15:25:40,766+02 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_3_M]
248
2020-04-20 15:25:40,767+02 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
249
2020-04-20 15:25:40,866+02 [Thread-7] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
250
2020-04-20 15:25:40,867+02 [Thread-7] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
251
  GET /tctoken HTTP/1.1
252
  Connection: keep-alive
253
  User-Agent: Open eCard App/1.4.1
254
  Host: eservice-idp-test.secunet.de:443
255
  Accept: text/xml, */*;q=0.8
256
  Accept-Charset: utf-8, *;q=0.8
257

    
258
2020-04-20 15:25:40,867+02 [Thread-7] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
259
2020-04-20 15:25:40,870+02 [Thread-7] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
260
2020-04-20 15:25:40,870+02 [Thread-7] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
261
java.net.SocketException: Connection reset
262
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
263
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
264
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
265
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
266
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
267
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
268
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
269
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
270
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
271
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
272
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
273
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
274
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
275
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
276
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
277
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
278
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
279
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
280
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
281
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
282
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
283
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
284
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
285
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
286
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
287
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
288
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
289
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
290
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
291
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
292
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
293
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
294
2020-04-20 15:25:40,882+02 [Thread-7] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
295
2020-04-20 15:25:40,887+02 [Thread-7] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
296
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
297
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
298
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
299
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
300
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
301
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
302
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
303
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
304
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
305
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
306
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
307
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
308
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
309
Caused by: java.net.SocketException: Connection reset
310
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
311
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
312
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
313
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
314
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
315
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
316
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
317
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
318
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
319
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
320
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
321
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
322
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
323
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
324
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
325
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
326
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
327
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
328
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
329
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
330
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
331
	... 11 common frames omitted
332
2020-04-20 15:25:40,887+02 [Thread-7] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
333
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
334

    
335
2020-04-20 15:25:40,887+02 [Thread-7] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
336
2020-04-20 15:25:40,888+02 [Thread-7] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
337
2020-04-20 15:25:40,888+02 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
338
2020-04-20 15:25:40,888+02 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
339
2020-04-20 15:25:40,888+02 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
340
2020-04-20 15:25:40,888+02 [Thread-7] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
341
2020-04-20 15:26:25,144+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
342
2020-04-20 15:26:25,146+02 [FuturePromise-7] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
343
2020-04-20 15:26:25,146+02 [Thread-8] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
344
2020-04-20 15:26:25,146+02 [Thread-8] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
345
2020-04-20 15:26:25,174+02 [Thread-8] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
346
2020-04-20 15:26:25,174+02 [Thread-8] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
347
2020-04-20 15:26:25,174+02 [Thread-8] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for java. Trying next one.
348
2020-04-20 15:26:25,174+02 [Thread-8] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Firefox settings folder not found!
349
2020-04-20 15:26:25,175+02 [Thread-8] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Strategy {0} failed trying next one.
350
com.github.markusbernhardt.proxy.util.ProxyException: No Firefox installation found
351
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.readSettings(FirefoxProxySearchStrategy.java:174)
352
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.getProxySelector(FirefoxProxySearchStrategy.java:106)
353
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.ProxySearch.getProxySelector(ProxySearch.java:216)
354
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettingsLoader$1.find(ProxySettingsLoader.java:169)
355
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.loadSelector(UpdatingProxySelector.java:57)
356
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.select(UpdatingProxySelector.java:67)
357
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getProxy(ProxySettings.java:123)
358
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getSocket(ProxySettings.java:149)
359
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:150)
360
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
361
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
362
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
363
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
364
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
365
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
366
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
367
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
368
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
369
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
370
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
371
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
372
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
373
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
374
2020-04-20 15:26:25,175+02 [Thread-8] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for desktop. Trying next one.
375
2020-04-20 15:26:25,175+02 [Thread-8] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for env. Trying next one.
376
2020-04-20 15:26:25,175+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
377
2020-04-20 15:26:25,175+02 [Thread-8] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
378
2020-04-20 15:26:25,176+02 [Thread-8] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
379
2020-04-20 15:26:25,195+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_3_M]
380
2020-04-20 15:26:25,196+02 [Thread-8] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
381
2020-04-20 15:26:25,290+02 [Thread-8] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
382
2020-04-20 15:26:25,290+02 [Thread-8] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
383
  GET /tctoken HTTP/1.1
384
  Connection: keep-alive
385
  User-Agent: Open eCard App/1.4.1
386
  Host: eservice-idp-test.secunet.de:443
387
  Accept: text/xml, */*;q=0.8
388
  Accept-Charset: utf-8, *;q=0.8
389

    
390
2020-04-20 15:26:25,291+02 [Thread-8] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
391
2020-04-20 15:26:25,299+02 [Thread-8] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
392
2020-04-20 15:26:25,299+02 [Thread-8] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
393
java.net.SocketException: Connection reset
394
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
395
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
396
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
397
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
398
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
399
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
400
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
401
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
402
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
403
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
404
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
405
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
406
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
407
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
408
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
409
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
410
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
411
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
412
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
413
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
414
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
415
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
416
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
417
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
418
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
419
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
420
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
421
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
422
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
423
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
424
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
425
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
426
2020-04-20 15:26:25,303+02 [Thread-8] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
427
2020-04-20 15:26:25,303+02 [Thread-8] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
428
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
429
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
430
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
431
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
432
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
433
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
434
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
435
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
436
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
437
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
438
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
439
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
440
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
441
Caused by: java.net.SocketException: Connection reset
442
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
443
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
444
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
445
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
446
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
447
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
448
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
449
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
450
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
451
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
452
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
453
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
454
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
455
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
456
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
457
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
458
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
459
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
460
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
461
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
462
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
463
	... 11 common frames omitted
464
2020-04-20 15:26:25,304+02 [Thread-8] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
465
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
466

    
467
2020-04-20 15:26:25,304+02 [Thread-8] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
468
2020-04-20 15:26:25,304+02 [Thread-8] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
469
2020-04-20 15:26:25,304+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
470
2020-04-20 15:26:25,304+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
471
2020-04-20 15:26:25,305+02 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
472
2020-04-20 15:26:25,305+02 [Thread-8] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
473
2020-04-20 15:26:36,328+02 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
474
2020-04-20 15:26:36,329+02 [Thread-9] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
475
2020-04-20 15:26:36,329+02 [FuturePromise-8] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
476
2020-04-20 15:26:36,329+02 [Thread-9] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
477
2020-04-20 15:26:36,368+02 [Thread-9] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
478
2020-04-20 15:26:36,369+02 [Thread-9] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
479
2020-04-20 15:26:36,369+02 [Thread-9] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
480
2020-04-20 15:26:36,369+02 [Thread-9] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
481
2020-04-20 15:26:36,370+02 [Thread-9] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
482
2020-04-20 15:26:36,386+02 [Thread-9] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
483
2020-04-20 15:26:36,387+02 [Thread-9] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
484
2020-04-20 15:26:36,422+02 [Thread-9] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
485
2020-04-20 15:26:36,423+02 [Thread-9] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
486
  GET /tctoken HTTP/1.1
487
  Connection: keep-alive
488
  User-Agent: Open eCard App/1.4.1
489
  Host: eservice-idp-test.secunet.de:443
490
  Accept: text/xml, */*;q=0.8
491
  Accept-Charset: utf-8, *;q=0.8
492

    
493
2020-04-20 15:26:36,423+02 [Thread-9] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
494
2020-04-20 15:26:39,304+02 [Thread-9] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
495
2020-04-20 15:26:39,305+02 [Thread-9] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
496
java.net.SocketException: Connection reset
497
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
498
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
499
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
500
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
501
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
502
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
503
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
504
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
505
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
506
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
507
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
508
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
509
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
510
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
511
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
512
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
513
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
514
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
515
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
516
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
517
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
518
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
519
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
520
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
521
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
522
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
523
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
524
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
525
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
526
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
527
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
528
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
529
2020-04-20 15:26:39,305+02 [Thread-9] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
530
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
531
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
532
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
533
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
534
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
535
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
536
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
537
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
538
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
539
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
540
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
541
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
542
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
543
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
544
Caused by: java.net.SocketException: Connection reset
545
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
546
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
547
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
548
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
549
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
550
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
551
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
552
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
553
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
554
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
555
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
556
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
557
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
558
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
559
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
560
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
561
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
562
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
563
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
564
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
565
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
566
	... 11 common frames omitted
567
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
568
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
569

    
570
2020-04-20 15:26:39,306+02 [Thread-9] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
571
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
572
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
573
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
574
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
575
2020-04-20 15:26:39,306+02 [Thread-9] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
576
2020-04-20 15:26:51,426+02 [Thread-10] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
577
2020-04-20 15:26:51,427+02 [FuturePromise-9] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
578
2020-04-20 15:26:51,427+02 [Thread-10] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
579
2020-04-20 15:26:51,427+02 [Thread-10] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
580
2020-04-20 15:26:51,467+02 [Thread-10] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
581
2020-04-20 15:26:51,467+02 [Thread-10] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
582
2020-04-20 15:26:51,467+02 [Thread-10] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
583
2020-04-20 15:26:51,468+02 [Thread-10] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
584
2020-04-20 15:26:51,468+02 [Thread-10] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
585
2020-04-20 15:26:51,481+02 [Thread-10] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
586
2020-04-20 15:26:51,481+02 [Thread-10] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
587
2020-04-20 15:26:51,522+02 [Thread-10] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
588
2020-04-20 15:26:51,522+02 [Thread-10] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
589
  GET /tctoken HTTP/1.1
590
  Connection: keep-alive
591
  User-Agent: Open eCard App/1.4.1
592
  Host: eservice-idp-test.secunet.de:443
593
  Accept: text/xml, */*;q=0.8
594
  Accept-Charset: utf-8, *;q=0.8
595

    
596
2020-04-20 15:26:51,522+02 [Thread-10] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
597
2020-04-20 15:27:08,020+02 [Thread-10] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
598
2020-04-20 15:27:08,021+02 [Thread-10] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
599
java.net.SocketException: Connection reset
600
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
601
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
602
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
603
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
604
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
605
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
606
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
607
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
608
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
609
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
610
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
611
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
612
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
613
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
614
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
615
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
616
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
617
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
618
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
619
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
620
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
621
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
622
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
623
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
624
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
625
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
626
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
627
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
628
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
629
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
630
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
631
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
632
2020-04-20 15:27:08,021+02 [Thread-10] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
633
2020-04-20 15:27:08,026+02 [Thread-10] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
634
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
635
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
636
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
637
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
638
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
639
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
640
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
641
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
642
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
643
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
644
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
645
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
646
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
647
Caused by: java.net.SocketException: Connection reset
648
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
649
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
650
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
651
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
652
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
653
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
654
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
655
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
656
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
657
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
658
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
659
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
660
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
661
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
662
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
663
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
664
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
665
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
666
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
667
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
668
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
669
	... 11 common frames omitted
670
2020-04-20 15:27:08,026+02 [Thread-10] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
671
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
672

    
673
2020-04-20 15:27:08,027+02 [Thread-10] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
674
2020-04-20 15:27:08,027+02 [Thread-10] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
675
2020-04-20 15:27:08,027+02 [Thread-10] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
676
2020-04-20 15:27:08,027+02 [Thread-10] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
677
2020-04-20 15:27:08,028+02 [Thread-10] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
678
2020-04-20 15:27:08,028+02 [Thread-10] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
679
2020-04-20 15:27:18,741+02 [Thread-11] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
680
2020-04-20 15:27:18,742+02 [FuturePromise-10] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
681
2020-04-20 15:27:18,743+02 [Thread-11] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
682
2020-04-20 15:27:18,743+02 [Thread-11] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
683
2020-04-20 15:27:18,769+02 [Thread-11] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
684
2020-04-20 15:27:18,770+02 [Thread-11] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
685
2020-04-20 15:27:18,770+02 [Thread-11] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for java. Trying next one.
686
2020-04-20 15:27:18,770+02 [Thread-11] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Firefox settings folder not found!
687
2020-04-20 15:27:18,770+02 [Thread-11] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Strategy {0} failed trying next one.
688
com.github.markusbernhardt.proxy.util.ProxyException: No Firefox installation found
689
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.readSettings(FirefoxProxySearchStrategy.java:174)
690
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.getProxySelector(FirefoxProxySearchStrategy.java:106)
691
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.ProxySearch.getProxySelector(ProxySearch.java:216)
692
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettingsLoader$1.find(ProxySettingsLoader.java:169)
693
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.loadSelector(UpdatingProxySelector.java:57)
694
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.select(UpdatingProxySelector.java:67)
695
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getProxy(ProxySettings.java:123)
696
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getSocket(ProxySettings.java:149)
697
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:150)
698
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
699
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
700
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
701
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
702
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
703
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
704
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
705
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
706
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
707
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
708
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
709
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
710
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
711
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
712
2020-04-20 15:27:18,771+02 [Thread-11] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for desktop. Trying next one.
713
2020-04-20 15:27:18,771+02 [Thread-11] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for env. Trying next one.
714
2020-04-20 15:27:18,771+02 [Thread-11] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
715
2020-04-20 15:27:18,771+02 [Thread-11] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
716
2020-04-20 15:27:18,772+02 [Thread-11] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
717
2020-04-20 15:27:18,784+02 [Thread-11] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
718
2020-04-20 15:27:18,784+02 [Thread-11] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
719
2020-04-20 15:27:18,836+02 [Thread-11] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
720
2020-04-20 15:27:18,837+02 [Thread-11] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
721
  GET /tctoken HTTP/1.1
722
  Connection: keep-alive
723
  User-Agent: Open eCard App/1.4.1
724
  Host: eservice-idp-test.secunet.de:443
725
  Accept: text/xml, */*;q=0.8
726
  Accept-Charset: utf-8, *;q=0.8
727

    
728
2020-04-20 15:27:18,837+02 [Thread-11] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
729
2020-04-20 15:28:07,486+02 [Thread-11] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
730
2020-04-20 15:28:07,487+02 [Thread-11] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
731
java.net.SocketException: Connection reset
732
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
733
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
734
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
735
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
736
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
737
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
738
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
739
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
740
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
741
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
742
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
743
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
744
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
745
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
746
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
747
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
748
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
749
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
750
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
751
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
752
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
753
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
754
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
755
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
756
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
757
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
758
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
759
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
760
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
761
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
762
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
763
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
764
2020-04-20 15:28:07,488+02 [Thread-11] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
765
2020-04-20 15:28:07,488+02 [Thread-11] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
766
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
767
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
768
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
769
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
770
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
771
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
772
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
773
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
774
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
775
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
776
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
777
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
778
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
779
Caused by: java.net.SocketException: Connection reset
780
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
781
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
782
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
783
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
784
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
785
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
786
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
787
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
788
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
789
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
790
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
791
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
792
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
793
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
794
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
795
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
796
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
797
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
798
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
799
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
800
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
801
	... 11 common frames omitted
802
2020-04-20 15:28:07,488+02 [Thread-11] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
803
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
804

    
805
2020-04-20 15:28:07,488+02 [Thread-11] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
806
2020-04-20 15:28:07,488+02 [Thread-11] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
807
2020-04-20 15:28:07,489+02 [Thread-11] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
808
2020-04-20 15:28:07,489+02 [Thread-11] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
809
2020-04-20 15:28:07,489+02 [Thread-11] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
810
2020-04-20 15:28:07,489+02 [Thread-11] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
811
2020-04-20 15:28:24,066+02 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
812
2020-04-20 15:28:24,068+02 [FuturePromise-11] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
813
2020-04-20 15:28:24,068+02 [Thread-13] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
814
2020-04-20 15:28:24,068+02 [Thread-13] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
815
2020-04-20 15:28:24,145+02 [Thread-13] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
816
2020-04-20 15:28:24,146+02 [Thread-13] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
817
2020-04-20 15:28:24,146+02 [Thread-13] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for java. Trying next one.
818
2020-04-20 15:28:24,146+02 [Thread-13] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Firefox settings folder not found!
819
2020-04-20 15:28:24,147+02 [Thread-13] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Strategy {0} failed trying next one.
820
com.github.markusbernhardt.proxy.util.ProxyException: No Firefox installation found
821
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.readSettings(FirefoxProxySearchStrategy.java:174)
822
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.getProxySelector(FirefoxProxySearchStrategy.java:106)
823
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.ProxySearch.getProxySelector(ProxySearch.java:216)
824
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettingsLoader$1.find(ProxySettingsLoader.java:169)
825
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.loadSelector(UpdatingProxySelector.java:57)
826
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.select(UpdatingProxySelector.java:67)
827
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getProxy(ProxySettings.java:123)
828
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getSocket(ProxySettings.java:149)
829
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:150)
830
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
831
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
832
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
833
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
834
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
835
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
836
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
837
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
838
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
839
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
840
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
841
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
842
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
843
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
844
2020-04-20 15:28:24,151+02 [Thread-13] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for desktop. Trying next one.
845
2020-04-20 15:28:24,151+02 [Thread-13] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for env. Trying next one.
846
2020-04-20 15:28:24,151+02 [Thread-13] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
847
2020-04-20 15:28:24,152+02 [Thread-13] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
848
2020-04-20 15:28:24,152+02 [Thread-13] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
849
2020-04-20 15:28:24,167+02 [Thread-13] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
850
2020-04-20 15:28:24,174+02 [Thread-13] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
851
2020-04-20 15:28:24,212+02 [Thread-13] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
852
2020-04-20 15:28:24,212+02 [Thread-13] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
853
  GET /tctoken HTTP/1.1
854
  Connection: keep-alive
855
  User-Agent: Open eCard App/1.4.1
856
  Host: eservice-idp-test.secunet.de:443
857
  Accept: text/xml, */*;q=0.8
858
  Accept-Charset: utf-8, *;q=0.8
859

    
860
2020-04-20 15:28:24,212+02 [Thread-13] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
861
2020-04-20 15:28:30,755+02 [Thread-13] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
862
2020-04-20 15:28:30,755+02 [Thread-13] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
863
java.net.SocketException: Connection reset
864
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
865
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
866
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
867
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
868
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
869
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
870
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
871
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
872
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
873
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
874
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
875
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
876
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
877
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
878
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
879
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
880
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
881
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
882
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
883
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
884
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
885
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
886
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
887
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
888
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
889
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
890
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
891
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
892
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
893
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
894
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
895
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
896
2020-04-20 15:28:30,756+02 [Thread-13] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
897
2020-04-20 15:28:30,756+02 [Thread-13] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
898
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
899
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
900
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
901
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
902
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
903
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
904
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
905
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
906
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
907
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
908
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
909
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
910
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
911
Caused by: java.net.SocketException: Connection reset
912
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
913
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
914
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
915
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
916
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
917
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
918
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
919
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
920
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
921
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
922
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
923
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
924
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
925
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
926
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
927
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
928
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
929
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
930
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
931
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
932
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
933
	... 11 common frames omitted
934
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
935
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
936

    
937
2020-04-20 15:28:30,757+02 [Thread-13] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
938
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
939
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
940
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
941
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
942
2020-04-20 15:28:30,757+02 [Thread-13] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
943
2020-04-20 15:28:43,318+02 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
944
2020-04-20 15:28:43,320+02 [FuturePromise-12] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
945
2020-04-20 15:28:43,320+02 [Thread-14] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
946
2020-04-20 15:28:43,320+02 [Thread-14] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
947
2020-04-20 15:28:43,366+02 [Thread-14] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
948
2020-04-20 15:28:43,367+02 [Thread-14] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
949
2020-04-20 15:28:43,368+02 [Thread-14] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
950
2020-04-20 15:28:43,368+02 [Thread-14] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
951
2020-04-20 15:28:43,369+02 [Thread-14] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
952
2020-04-20 15:28:43,393+02 [Thread-14] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
953
2020-04-20 15:28:43,393+02 [Thread-14] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
954
2020-04-20 15:28:43,452+02 [Thread-14] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
955
2020-04-20 15:28:43,453+02 [Thread-14] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
956
  GET /tctoken HTTP/1.1
957
  Connection: keep-alive
958
  User-Agent: Open eCard App/1.4.1
959
  Host: eservice-idp-test.secunet.de:443
960
  Accept: text/xml, */*;q=0.8
961
  Accept-Charset: utf-8, *;q=0.8
962

    
963
2020-04-20 15:28:43,454+02 [Thread-14] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
964
2020-04-20 15:28:47,590+02 [Thread-14] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
965
2020-04-20 15:28:47,591+02 [Thread-14] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
966
java.net.SocketException: Connection reset
967
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
968
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
969
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
970
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
971
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
972
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
973
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
974
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
975
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
976
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
977
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
978
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
979
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
980
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
981
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
982
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
983
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
984
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
985
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
986
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
987
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
988
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
989
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
990
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
991
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
992
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
993
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
994
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
995
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
996
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
997
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
998
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
999
2020-04-20 15:28:47,591+02 [Thread-14] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
1000
2020-04-20 15:28:47,591+02 [Thread-14] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
1001
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
1002
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
1003
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
1004
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
1005
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
1006
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
1007
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
1008
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
1009
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
1010
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
1011
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
1012
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
1013
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
1014
Caused by: java.net.SocketException: Connection reset
1015
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
1016
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
1017
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
1018
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
1019
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
1020
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
1021
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
1022
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
1023
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
1024
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
1025
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
1026
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
1027
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
1028
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
1029
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
1030
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
1031
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
1032
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
1033
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
1034
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
1035
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
1036
	... 11 common frames omitted
1037
2020-04-20 15:28:47,591+02 [Thread-14] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
1038
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
1039

    
1040
2020-04-20 15:28:47,592+02 [Thread-14] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
1041
2020-04-20 15:28:47,592+02 [Thread-14] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
1042
2020-04-20 15:28:47,592+02 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
1043
2020-04-20 15:28:47,592+02 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
1044
2020-04-20 15:28:47,592+02 [Thread-14] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
1045
2020-04-20 15:28:47,592+02 [Thread-14] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
1046
2020-04-20 15:29:15,702+02 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:87 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Feservice-idp-test.secunet.de%3A443%2Ftctoken [Host: 127.0.0.1:24727, Connection: Keep-Alive, User-Agent: Apache-HttpClient/4.2.5 (java 1.5)]
1047
2020-04-20 15:29:15,706+02 [FuturePromise-13] WARN  o.o.common.util.JAXPSchemaValidator:143 - Did not find a default SchemaFactory.
1048
2020-04-20 15:29:15,706+02 [Thread-15] DEBUG org.openecard.sal.SelectorSAL:205 - Requesting CIF from CIF-Repo for type=http://bsi.bund.de/cif/npa.xml.
1049
2020-04-20 15:29:15,707+02 [Thread-15] DEBUG o.o.r.staticrepo.LocalCifRepo:131 - Requesting CIFs with GetCardInfoOrACD call.
1050
2020-04-20 15:29:15,758+02 [Thread-15] DEBUG o.o.r.staticrepo.LocalCifRepo:172 - Returning CIFs from GetCardInfoOrACD call.
1051
2020-04-20 15:29:15,759+02 [Thread-15] INFO  o.o.httpcore.ResourceContextLoader:115 - Trying to load resource from: https://eservice-idp-test.secunet.de:443/tctoken
1052
2020-04-20 15:29:15,759+02 [Thread-15] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for java. Trying next one.
1053
2020-04-20 15:29:15,759+02 [Thread-15] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Firefox settings folder not found!
1054
2020-04-20 15:29:15,760+02 [Thread-15] DEBUG c.g.m.p.util.Logger$Slf4jLogBackEnd:83 - Strategy {0} failed trying next one.
1055
com.github.markusbernhardt.proxy.util.ProxyException: No Firefox installation found
1056
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.readSettings(FirefoxProxySearchStrategy.java:174)
1057
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.search.browser.firefox.FirefoxProxySearchStrategy.getProxySelector(FirefoxProxySearchStrategy.java:106)
1058
	at org.openecard.richclient@1.4.1/com.github.markusbernhardt.proxy.ProxySearch.getProxySelector(ProxySearch.java:216)
1059
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettingsLoader$1.find(ProxySettingsLoader.java:169)
1060
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.loadSelector(UpdatingProxySelector.java:57)
1061
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.UpdatingProxySelector.select(UpdatingProxySelector.java:67)
1062
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getProxy(ProxySettings.java:123)
1063
	at org.openecard.richclient@1.4.1/org.openecard.crypto.tls.proxy.ProxySettings.getSocket(ProxySettings.java:149)
1064
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:150)
1065
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
1066
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
1067
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
1068
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
1069
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
1070
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
1071
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
1072
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
1073
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
1074
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
1075
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
1076
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
1077
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
1078
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
1079
2020-04-20 15:29:15,760+02 [Thread-15] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for desktop. Trying next one.
1080
2020-04-20 15:29:15,760+02 [Thread-15] INFO  c.g.m.p.util.Logger$Slf4jLogBackEnd:73 - No proxy found for env. Trying next one.
1081
2020-04-20 15:29:15,760+02 [Thread-15] DEBUG o.o.crypto.tls.proxy.ProxySettings:133 - Selecting proxy: DIRECT
1082
2020-04-20 15:29:15,760+02 [Thread-15] DEBUG o.o.crypto.tls.proxy.ProxySettings:168 - Using proxy (DIRECT) to obtain socket.
1083
2020-04-20 15:29:15,761+02 [Thread-15] DEBUG o.o.httpcore.ResourceContextLoader:152 - Performing TLS handshake.
1084
2020-04-20 15:29:15,780+02 [Thread-15] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [CERT_TLS_ESERVICE_1]
1085
2020-04-20 15:29:15,780+02 [Thread-15] DEBUG o.o.c.tls.verify.HostnameVerifier:126 - Comparing connection hostname against certificate hostname: [eservice-idp-test.secunet.de] [eservice-idp-test.secunet.de]
1086
2020-04-20 15:29:15,822+02 [Thread-15] DEBUG o.o.httpcore.ResourceContextLoader:154 - TLS handshake performed.
1087
2020-04-20 15:29:15,825+02 [Thread-15] DEBUG org.openecard.httpcore.HttpUtils:81 - HTTP Request:
1088
  GET /tctoken HTTP/1.1
1089
  Connection: keep-alive
1090
  User-Agent: Open eCard App/1.4.1
1091
  Host: eservice-idp-test.secunet.de:443
1092
  Accept: text/xml, */*;q=0.8
1093
  Accept-Charset: utf-8, *;q=0.8
1094

    
1095
2020-04-20 15:29:15,828+02 [Thread-15] DEBUG o.o.httpcore.ResourceContextLoader:175 - Sending HTTP request.
1096
2020-04-20 15:29:18,166+02 [Thread-15] ERROR o.o.c.t.ClientCertDefaultTlsClient:303 - TLS error sent.
1097
2020-04-20 15:29:18,166+02 [Thread-15] ERROR o.o.c.t.ClientCertDefaultTlsClient:304 - TLS(FATAL): Internal error [internal_error=80] --> Failed to read record
1098
java.net.SocketException: Connection reset
1099
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
1100
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
1101
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
1102
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
1103
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
1104
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
1105
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
1106
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
1107
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
1108
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
1109
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
1110
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
1111
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
1112
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
1113
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
1114
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
1115
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
1116
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
1117
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
1118
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
1119
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
1120
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
1121
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
1122
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
1123
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
1124
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
1125
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
1126
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
1127
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
1128
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
1129
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
1130
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
1131
2020-04-20 15:29:18,167+02 [Thread-15] ERROR o.o.addons.activate.ActivateAction:422 - Failed to retrieve the TCToken.
1132
2020-04-20 15:29:18,167+02 [Thread-15] DEBUG o.o.addons.activate.ActivateAction:423 - Failed to retrieve the TCToken.
1133
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
1134
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:87)
1135
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:197)
1136
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:116)
1137
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processTcToken(ActivateAction.java:408)
1138
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.processRequest(ActivateAction.java:265)
1139
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.checkRequestParameters(ActivateAction.java:246)
1140
	at org.openecard.richclient@1.4.1/org.openecard.addons.activate.ActivateAction.execute(ActivateAction.java:127)
1141
	at org.openecard.richclient@1.4.1/org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:55)
1142
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:126)
1143
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.doService(HttpService.java:437)
1144
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpService.handleRequest(HttpService.java:342)
1145
	at org.openecard.richclient@1.4.1/org.openecard.control.binding.http.HttpService$1.run(HttpService.java:132)
1146
Caused by: java.net.SocketException: Connection reset
1147
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:186)
1148
	at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
1149
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.fillTo(Unknown Source)
1150
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream$Record.readHeader(Unknown Source)
1151
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.RecordStream.readRecord(Unknown Source)
1152
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.safeReadRecord(Unknown Source)
1153
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsProtocol.readApplicationData(Unknown Source)
1154
	at org.openecard.richclient@1.4.1/org.openecard.bouncycastle.tls.TlsInputStream.read(Unknown Source)
1155
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
1156
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
1157
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
1158
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:129)
1159
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:53)
1160
	at org.openecard.richclient@1.4.1/org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
1161
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.StreamHttpClientConnection.receiveResponseHeader(StreamHttpClientConnection.java:193)
1162
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
1163
	at org.openecard.richclient@1.4.1/org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
1164
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStreamInt(ResourceContextLoader.java:176)
1165
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:107)
1166
	at org.openecard.richclient@1.4.1/org.openecard.httpcore.ResourceContextLoader.getStream(ResourceContextLoader.java:81)
1167
	at org.openecard.richclient@1.4.1/org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:80)
1168
	... 11 common frames omitted
1169
2020-04-20 15:29:18,167+02 [Thread-15] DEBUG o.o.addons.activate.ActivateAction:424 - Returning result: 
1170
BindingResult <RESOURCE_UNAVAILABLE, 'Failed to retrieve the TCToken.'>
1171

    
1172
2020-04-20 15:29:18,167+02 [Thread-15] INFO  o.o.addons.activate.ActivateAction:426 - Authentication failed, displaying error in Browser.
1173
2020-04-20 15:29:18,168+02 [Thread-15] DEBUG org.openecard.common.DynamicContext:96 - Removing DynamicContext which contains 1 map entries.
1174
2020-04-20 15:29:18,168+02 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:207 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
1175
2020-04-20 15:29:18,168+02 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:196 - BindingResult contains no body.
1176
2020-04-20 15:29:18,168+02 [Thread-15] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:130 - HTTP response: HTTP/1.1 404  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 31,Chunked: false]
1177
2020-04-20 15:29:18,168+02 [Thread-15] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:99 - HTTP response intercepted
(1-1/2)