Project

General

Profile

Bug #365 » richclient_info-sessionid-buergerservice-org-idsafe-wuerzburg.log

Detlef Hühnlein, 11/22/2014 06:10 PM

 
1
2014-11-22 12:52:52,101 [main] INFO  o.o.richclient.LogbackConfig:70 - Configured Logback with config file from: C:\Users\H?hni\.openecard\richclient_logback.xml
2
2014-11-22 12:52:52,791 [main] WARN  org.openecard.common.I18n:151 - Failed to load resource recognition for lang de_DE.
3
2014-11-22 12:52:54,858 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal TR-03112 add-on.
4
2014-11-22 12:52:54,859 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal PIN-Management add-on.
5
2014-11-22 12:52:54,860 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal GenericCrypto add-on.
6
2014-11-22 12:52:54,860 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal Status add-on.
7
2014-11-22 12:52:54,860 [main] WARN  o.openecard.addon.ClasspathRegistry:74 - Skipped loading internal add-on PKCS#11, because it is not available.
8
2014-11-22 12:52:54,862 [main] DEBUG org.openecard.addon.FileRegistry:95 - Starting file alteration monitor on path: C:\Users\H?hni\.openecard\addons
9
2014-11-22 12:52:54,877 [main] WARN  org.openecard.common.I18n:151 - Failed to load resource http for lang de_DE.
10
2014-11-22 12:52:54,885 [main] DEBUG o.o.c.binding.http.HTTPService:73 - Starting HTTPBinding on port 24727
11
2014-11-22 12:52:54,888 [main] DEBUG o.o.c.binding.http.HTTPService:84 - Add handler [org.openecard.control.binding.http.handler.HttpAppPluginActionHandler] for ID [*]
12
2014-11-22 12:52:55,043 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:76 - Difference in status detected.
13
2014-11-22 12:52:55,043 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:97 - Analyzing IFD event.
14
2014-11-22 12:52:55,044 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:105 - Found a terminal added event (Broadcom Corp Contacted SmartCard 0).
15
2014-11-22 12:52:55,048 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:326 - Event: TERMINAL_ADDED
16
2014-11-22 12:52:55,048 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:105 - Found a terminal added event (REINER SCT cyberJack RFID basis 0).
17
2014-11-22 12:52:55,070 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@4bf576
18
2014-11-22 12:52:55,072 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:326 - Event: TERMINAL_ADDED
19
2014-11-22 12:52:55,072 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:118 - Found a card insert event (REINER SCT cyberJack RFID basis 0).
20
2014-11-22 12:52:55,072 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: null
21
2014-11-22 12:52:55,072 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@13a49a2
22
2014-11-22 12:52:55,073 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: Broadcom Corp Contacted SmartCard 0
23
2014-11-22 12:52:55,074 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: null
24
2014-11-22 12:52:55,074 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:326 - Event: CARD_INSERTED
25
2014-11-22 12:52:55,074 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
26
2014-11-22 12:52:55,074 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1b97f95
27
2014-11-22 12:52:55,075 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@17e8092
28
2014-11-22 12:52:55,075 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
29
2014-11-22 12:52:55,192 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
30
2014-11-22 12:52:55,218 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
31
2014-11-22 12:52:55,222 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
32
2014-11-22 12:52:55,233 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6A 0x82 
33
2014-11-22 12:52:55,238 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
34
2014-11-22 12:52:55,249 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
35
2014-11-22 12:52:55,251 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
36
2014-11-22 12:52:55,263 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
37
2014-11-22 12:52:55,264 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
38
2014-11-22 12:52:55,274 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6D 0x00 
39
2014-11-22 12:52:55,276 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
40
2014-11-22 12:52:55,286 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
41
2014-11-22 12:52:55,288 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
42
2014-11-22 12:52:55,299 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6A 0x82 
43
2014-11-22 12:52:55,300 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
44
2014-11-22 12:52:55,311 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
45
2014-11-22 12:52:55,312 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
46
2014-11-22 12:52:55,325 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
47
2014-11-22 12:52:55,326 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
48
2014-11-22 12:52:55,335 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6D 0x00 
49
2014-11-22 12:52:55,336 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x00 
50
2014-11-22 12:52:55,345 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x67 0x00 
51
2014-11-22 12:52:55,347 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
52
2014-11-22 12:52:55,357 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x67 0x00 
53
2014-11-22 12:52:55,359 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
54
2014-11-22 12:52:55,370 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
55
2014-11-22 12:52:55,371 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
56
2014-11-22 12:52:55,384 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
57
2014-11-22 12:52:55,385 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
58
2014-11-22 12:52:55,404 [pool-2-thread-7] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x61 0x32 0x4F 0x0F 0xE8 0x28 0xBD 0x08 0x0F 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x50 0x0F 0x43 0x49 0x41 0x20 0x7A 0x75 0x20 0x44 0x46 0x2E 0x65 0x53 0x69 0x67 0x6E 0x51 0x00 0x73 0x0C 0x4F 0x0A 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x61 0x09 0x4F 0x07 0xA0 0x00 0x00 0x02 0x47 0x10 0x01 0x61 0x0B 0x4F 0x09 0xE8 0x07 0x04 0x00 0x7F 0x00 0x07 0x03 0x02 0x61 0x0C 0x4F 0x0A 0xA0 0x00 0x00 0x01 0x67 0x45 0x53 0x49 0x47 0x4E 0x62 0x82 
59
2014-11-22 12:52:55,408 [pool-2-thread-7] DEBUG org.openecard.event.Recognizer:55 - Found a recognized card event (REINER SCT cyberJack RFID basis 0).
60
2014-11-22 12:52:55,409 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:326 - Event: CARD_RECOGNIZED
61
2014-11-22 12:52:55,409 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1e8ac1
62
2014-11-22 12:52:55,410 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@e2f768
63
2014-11-22 12:52:55,410 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
64
2014-11-22 12:52:55,414 [pool-2-thread-6] INFO  o.o.c.sal.state.SALStateCallback:60 - Add ConnectionHandle to SAL:
65
ConnectionHandle:
66
  Session: Ls2C_xFvMOmRhjOnRg_d8A
67
  ContextHandle: 9FC7B83DA6964A415B3C7F68955A0513
68
  IFDName: REINER SCT cyberJack RFID basis 0  SlotIndex: 0
69
  CardType: http://bsi.bund.de/cif/npa.xml
70
2014-11-22 12:53:18,957 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:101 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Fwww.buergerservice.org%2FID-Safe-Wuerzburg%2Fpoll%2Flogin [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Connection: keep-alive]
71
2014-11-22 12:53:18,963 [Thread-5] WARN  org.openecard.common.I18n:151 - Failed to load resource tr03112 for lang de_DE.
72
2014-11-22 12:53:18,966 [Thread-5] WARN  org.openecard.common.I18n:151 - Failed to load resource tctoken for lang de_DE.
73
2014-11-22 12:53:18,970 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://www.buergerservice.org/ID-Safe-Wuerzburg/poll/login
74
2014-11-22 12:53:18,980 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
75
2014-11-22 12:53:18,981 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
76
2014-11-22 12:53:19,119 [Thread-5] ERROR o.o.b.tctoken.ActivationAction:101 - Failed to retrieve the TCToken.
77
2014-11-22 12:53:19,133 [Thread-5] DEBUG o.o.b.tctoken.ActivationAction:102 - Failed to retrieve the TCToken.
78
org.openecard.binding.tctoken.ex.TCTokenRetrievalException: Failed to retrieve the TCToken.
79
	at org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:74) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
80
	at org.openecard.binding.tctoken.TCTokenRequest.parseTCTokenRequestURI(TCTokenRequest.java:118) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
81
	at org.openecard.binding.tctoken.TCTokenRequest.convert(TCTokenRequest.java:88) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
82
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:87) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
83
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:52) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
84
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:127) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
85
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
86
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
87
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
88
Caused by: java.io.IOException: Internal TLS error, this could be an attack
89
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.failWithError(TlsProtocol.java:655) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
90
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.safeReadRecord(TlsProtocol.java:475) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
91
	at org.openecard.bouncycastle.crypto.tls.TlsProtocol.completeHandshake(TlsProtocol.java:152) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
92
	at org.openecard.bouncycastle.crypto.tls.TlsClientProtocol.connect(TlsClientProtocol.java:104) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
93
	at org.openecard.binding.tctoken.ResourceContext.getStreamInt(ResourceContext.java:213) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
94
	at org.openecard.binding.tctoken.ResourceContext.getStream(ResourceContext.java:174) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
95
	at org.openecard.binding.tctoken.ResourceContext.getStream(ResourceContext.java:151) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
96
	at org.openecard.binding.tctoken.TCTokenContext.generateTCToken(TCTokenContext.java:71) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
97
	... 8 common frames omitted
98
2014-11-22 12:53:19,134 [Thread-5] DEBUG o.o.b.tctoken.ActivationAction:103 - Returning result: 
99
BindingResult <RESOURCE_UNAVAILABLE, 'Das TCToken konnte nicht empfangen werden.'>
100

    
101
2014-11-22 12:53:19,134 [Thread-5] INFO  o.o.b.tctoken.ActivationAction:105 - Authentication failed, displaying error in Browser.
102
2014-11-22 12:53:19,134 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:183 - Recieved BindingResult with ResultCode RESOURCE_UNAVAILABLE
103
2014-11-22 12:53:19,135 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:165 - BindingResult contains NO body.
104
2014-11-22 12:53:19,138 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:131 - HTTP response: HTTP/1.1 404  []
105
2014-11-22 12:53:19,141 [Thread-5] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:86 - HTTP response intercepted
106
2014-11-22 12:53:19,153 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:101 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/css,*/*;q=0.1, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fwww.buergerservice.org%2FID-Safe-Wuerzburg%2Fpoll%2Flogin, Connection: keep-alive]
107
2014-11-22 12:53:19,155 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:82 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/css,*/*;q=0.1, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fwww.buergerservice.org%2FID-Safe-Wuerzburg%2Fpoll%2Flogin, Connection: keep-alive]
108
2014-11-22 12:53:19,155 [Thread-7] DEBUG o.o.c.b.h.h.common.FileHandler:75 - Handle file request
109
2014-11-22 12:53:19,157 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:104 - HTTP response: HTTP/1.1 200  [Content-Type: text/css; charset=UTF-8]
110
2014-11-22 12:53:19,157 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:105 - HTTP request handled by: org.openecard.control.binding.http.handler.common.FileHandler
111
2014-11-22 12:53:19,193 [Thread-8] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:101 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Connection: keep-alive]
112
2014-11-22 12:53:19,193 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:82 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Connection: keep-alive]
113
2014-11-22 12:53:19,193 [Thread-8] DEBUG o.o.c.b.h.h.common.FileHandler:75 - Handle file request
114
2014-11-22 12:53:19,193 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:104 - HTTP response: HTTP/1.1 200  [Content-Type: image/vnd.microsoft.icon; charset=UTF-8]
115
2014-11-22 12:53:19,194 [Thread-8] DEBUG o.o.c.b.h.h.ControlCommonHandler:105 - HTTP request handled by: org.openecard.control.binding.http.handler.common.FileHandler
116
2014-11-22 12:53:19,195 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:101 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Connection: keep-alive]
117
2014-11-22 12:53:19,196 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:82 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:33.0) Gecko/20100101 Firefox/33.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: de,en-US;q=0.7,en;q=0.3, Accept-Encoding: gzip, deflate, Connection: keep-alive]
118
2014-11-22 12:53:19,196 [Thread-9] DEBUG o.o.c.b.h.h.common.FileHandler:75 - Handle file request
119
2014-11-22 12:53:19,196 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:104 - HTTP response: HTTP/1.1 200  [Content-Type: image/vnd.microsoft.icon; charset=UTF-8]
120
2014-11-22 12:53:19,196 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:105 - HTTP request handled by: org.openecard.control.binding.http.handler.common.FileHandler
121
2014-11-22 12:53:31,646 [Open-eCard Localhost-Binding] ERROR o.o.c.binding.http.HTTPService:138 - socket closed
122
java.net.SocketException: socket closed
123
	at java.net.DualStackPlainSocketImpl.accept0(Native Method) ~[na:1.8.0_25]
124
	at java.net.DualStackPlainSocketImpl.socketAccept(Unknown Source) ~[na:1.8.0_25]
125
	at java.net.AbstractPlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_25]
126
	at java.net.PlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_25]
127
	at java.net.ServerSocket.implAccept(Unknown Source) ~[na:1.8.0_25]
128
	at java.net.ServerSocket.accept(Unknown Source) ~[na:1.8.0_25]
129
	at org.openecard.control.binding.http.HTTPService.run(HTTPService.java:117) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
130
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
131
2014-11-22 12:53:31,648 [pool-2-thread-2] WARN  org.openecard.ifd.scio.IFD:482 - null
132
java.lang.InterruptedException: null
133
	at java.util.concurrent.FutureTask.awaitDone(Unknown Source) [na:1.8.0_25]
134
	at java.util.concurrent.FutureTask.get(Unknown Source) [na:1.8.0_25]
135
	at org.openecard.ifd.scio.IFD.wait(IFD.java:467) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
136
	at org.openecard.event.WaitFuture.call(WaitFuture.java:54) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
137
	at org.openecard.event.WaitFuture.call(WaitFuture.java:39) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
138
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
139
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
140
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
141
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
142
2014-11-22 12:53:31,648 [pool-1-thread-1] WARN  o.openecard.ifd.scio.EventListener:140 - null
143
java.lang.InterruptedException: null
144
	at java.util.concurrent.FutureTask.awaitDone(Unknown Source) [na:1.8.0_25]
145
	at java.util.concurrent.FutureTask.get(Unknown Source) [na:1.8.0_25]
146
	at org.openecard.ifd.scio.EventListener.waitForEvent(EventListener.java:173) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
147
	at org.openecard.ifd.scio.EventListener.call(EventListener.java:128) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
148
	at org.openecard.ifd.scio.EventListener.call(EventListener.java:54) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
149
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
150
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
151
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
152
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
153
2014-11-22 12:53:31,649 [pool-2-thread-2] WARN  org.openecard.event.WaitFuture:59 - http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError ==> Unknown eCard exception occurred.
154
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError ==> Unknown eCard exception occurred.
155
	at org.openecard.common.ECardException.makeException(ECardException.java:64) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
156
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:46) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
157
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:68) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
158
	at org.openecard.event.WaitFuture.call(WaitFuture.java:56) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
159
	at org.openecard.event.WaitFuture.call(WaitFuture.java:39) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
160
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
161
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
162
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
163
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
(2-2/2)