Project

General

Profile

Bug #365 » richclient_info-sessionid-portal-ego-mv.log

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

 
1
2014-11-22 12:57:14,524 [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:57:14,887 [main] WARN  org.openecard.common.I18n:151 - Failed to load resource recognition for lang de_DE.
3
2014-11-22 12:57:16,848 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal TR-03112 add-on.
4
2014-11-22 12:57:16,849 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal PIN-Management add-on.
5
2014-11-22 12:57:16,850 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal GenericCrypto add-on.
6
2014-11-22 12:57:16,850 [main] INFO  o.openecard.addon.ClasspathRegistry:79 - Loaded internal Status add-on.
7
2014-11-22 12:57:16,850 [main] WARN  o.openecard.addon.ClasspathRegistry:74 - Skipped loading internal add-on PKCS#11, because it is not available.
8
2014-11-22 12:57:16,852 [main] DEBUG org.openecard.addon.FileRegistry:95 - Starting file alteration monitor on path: C:\Users\H?hni\.openecard\addons
9
2014-11-22 12:57:16,866 [main] WARN  org.openecard.common.I18n:151 - Failed to load resource http for lang de_DE.
10
2014-11-22 12:57:16,871 [main] DEBUG o.o.c.binding.http.HTTPService:73 - Starting HTTPBinding on port 24727
11
2014-11-22 12:57:16,874 [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:57:17,031 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:76 - Difference in status detected.
13
2014-11-22 12:57:17,032 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:97 - Analyzing IFD event.
14
2014-11-22 12:57:17,032 [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:57:17,035 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:326 - Event: TERMINAL_ADDED
16
2014-11-22 12:57:17,036 [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:57:17,036 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1d25db0
18
2014-11-22 12:57:17,037 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: null
19
2014-11-22 12:57:17,037 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: Broadcom Corp Contacted SmartCard 0
20
2014-11-22 12:57:17,038 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:118 - Found a card insert event (REINER SCT cyberJack RFID basis 0).
21
2014-11-22 12:57:17,039 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:326 - Event: TERMINAL_ADDED
22
2014-11-22 12:57:17,040 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@a8f250
23
2014-11-22 12:57:17,041 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: null
24
2014-11-22 12:57:17,041 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
25
2014-11-22 12:57:17,044 [pool-2-thread-9] DEBUG org.openecard.richclient.gui.Status:326 - Event: CARD_INSERTED
26
2014-11-22 12:57:17,044 [pool-2-thread-9] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1b97f95
27
2014-11-22 12:57:17,045 [pool-2-thread-9] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@17e8092
28
2014-11-22 12:57:17,046 [pool-2-thread-9] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
29
2014-11-22 12:57:17,175 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
30
2014-11-22 12:57:17,201 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
31
2014-11-22 12:57:17,205 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
32
2014-11-22 12:57:17,216 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6A 0x82 
33
2014-11-22 12:57:17,222 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
34
2014-11-22 12:57:17,233 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
35
2014-11-22 12:57:17,234 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
36
2014-11-22 12:57:17,247 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
37
2014-11-22 12:57:17,248 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
38
2014-11-22 12:57:17,257 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6D 0x00 
39
2014-11-22 12:57:17,259 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
40
2014-11-22 12:57:17,270 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
41
2014-11-22 12:57:17,272 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
42
2014-11-22 12:57:17,282 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6A 0x82 
43
2014-11-22 12:57:17,284 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
44
2014-11-22 12:57:17,295 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
45
2014-11-22 12:57:17,296 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
46
2014-11-22 12:57:17,309 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
47
2014-11-22 12:57:17,310 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
48
2014-11-22 12:57:17,319 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x6D 0x00 
49
2014-11-22 12:57:17,321 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x00 
50
2014-11-22 12:57:17,331 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x67 0x00 
51
2014-11-22 12:57:17,333 [pool-2-thread-12] 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:57:17,343 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x67 0x00 
53
2014-11-22 12:57:17,345 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
54
2014-11-22 12:57:17,355 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
55
2014-11-22 12:57:17,357 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
56
2014-11-22 12:57:17,369 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
57
2014-11-22 12:57:17,370 [pool-2-thread-12] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
58
2014-11-22 12:57:17,390 [pool-2-thread-12] 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:57:17,394 [pool-2-thread-12] DEBUG org.openecard.event.Recognizer:55 - Found a recognized card event (REINER SCT cyberJack RFID basis 0).
60
2014-11-22 12:57:17,394 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:326 - Event: CARD_RECOGNIZED
61
2014-11-22 12:57:17,395 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:330 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@144e95d
62
2014-11-22 12:57:17,395 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:332 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@133ea8d
63
2014-11-22 12:57:17,396 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:334 - IFDName: REINER SCT cyberJack RFID basis 0
64
2014-11-22 12:57:17,397 [pool-2-thread-9] INFO  o.o.c.sal.state.SALStateCallback:60 - Add ConnectionHandle to SAL:
65
ConnectionHandle:
66
  Session: dS1Z02IQbRAPkgm0xvkpig
67
  ContextHandle: CE8CC1C8BB660931A6428F4833D5C668
68
  IFDName: REINER SCT cyberJack RFID basis 0  SlotIndex: 0
69
  CardType: http://bsi.bund.de/cif/npa.xml
70
2014-11-22 12:58:07,883 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:101 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Ftbk.ego-mv.de%2FBuergerKontoWeb%2FSAMLRequestServlet%3FsessionId%3D261c0d84-46bd-4ed5-9c76-a1b3e6e17c14 [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:58:07,890 [Thread-5] WARN  org.openecard.common.I18n:151 - Failed to load resource tr03112 for lang de_DE.
72
2014-11-22 12:58:07,893 [Thread-5] WARN  org.openecard.common.I18n:151 - Failed to load resource tctoken for lang de_DE.
73
2014-11-22 12:58:07,898 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLRequestServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14
74
2014-11-22 12:58:07,907 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
75
2014-11-22 12:58:07,907 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
76
2014-11-22 12:58:08,252 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
77
  GET /BuergerKontoWeb/SAMLRequestServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14 HTTP/1.1
78
  Connection: keep-alive
79
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
80
  Host: tbk.ego-mv.de
81
  Accept: text/xml, */*;q=0.8
82
  Accept-Charset: utf-8, *;q=0.8
83

    
84
2014-11-22 12:58:08,316 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
85
  HTTP/1.1 303 See Other
86
  Server: Apache-Coyote/1.1
87
  Cache-Control: no-cache, no-store
88
  Pragma: no-cache
89
  Location: https://prod.governikus-eid.de/gov_autent/async?SAMLRequest=jZJbS8MwGIb%2FSsl92iZ23RbWjakIwwMynYI3kiYfW9iazHxp0X9vqhvqjQxyk%2BPz8j6ZzN6bXdKBR%2BNsRViakwSsctrYdUVWj1d0RGbTCcpmx%2Fdi3oaNXcJbCxiSeNGi%2BN6pSOutcBINCisbQBGUeJjf3gie5mLvXXDK7UgyRwQfIurCWWwb8A%2FgO6NgtbypyCaEPYosC%2FU2hbWjTZdqyM5b8Gvw184G9wx11r%2B6BAWmg%2F7yDsIMAfv4C13xkqlcjwpalLWmBegBHathSSWrz6AENlSsIMlljG%2Bs7HP8UGNIna5drMKabYsUjO7xceVVtgFsyCR%2BWEWSxWVFQPI42JgOeR5h9UhTWUdYqUvNNa9zGAziScQWFhaDtKEiPGcFZYxy%2FsiYGIwEz9NhyV9Icu9dZzT4u9jcqTWQ5OkoLVZMDorEF9L%2FdvO%2FGnkUQqan1T%2FJvowfQNPD9O%2FXmH4C&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%2Frsa-sha256&Signature=teywNzhLQzLDDidFi9riYEwbIhNZbf0S6qdnosna3lpgR%2Fl6dcaVFGbq%2F2A6%2FKSjeWpDWx68QSdA%0A9DWicCZHaB0nhLZll4RP9W8H4Ok7fWb4oeNf6j3wot7JSma%2FNOJUXyyHQPa3bwOEnn3lCWSWnzbx%0AqIM8mDd2COq0Ix1l5QMQ8qAzIKQ7pG1ep3J0twlzkfP8Oy4OGBSdnxcbrJ8ZEReeHjY7aHOnH1Cb%0AeqZD3Mv67dXEje2ohTiS50izbrI%2FAD9uHvwKSR%2BLVBWIdeRx3j1RZ%2F99ZJhN8sMxhNVfkbEXgBo%2B%0AF74YZnqbXVtJY52ZUMN3o%2BzTWmKjEVLq2e09Qw%3D%3D
90
  Content-Type: text/html
91
  Content-Length: 0
92
  Date: Sat, 22 Nov 2014 11:58:20 GMT
93

    
94

    
95
2014-11-22 12:58:08,318 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://prod.governikus-eid.de/gov_autent/async?SAMLRequest=jZJbS8MwGIb%2FSsl92iZ23RbWjakIwwMynYI3kiYfW9iazHxp0X9vqhvqjQxyk%2BPz8j6ZzN6bXdKBR%2BNsRViakwSsctrYdUVWj1d0RGbTCcpmx%2Fdi3oaNXcJbCxiSeNGi%2BN6pSOutcBINCisbQBGUeJjf3gie5mLvXXDK7UgyRwQfIurCWWwb8A%2FgO6NgtbypyCaEPYosC%2FU2hbWjTZdqyM5b8Gvw184G9wx11r%2B6BAWmg%2F7yDsIMAfv4C13xkqlcjwpalLWmBegBHathSSWrz6AENlSsIMlljG%2Bs7HP8UGNIna5drMKabYsUjO7xceVVtgFsyCR%2BWEWSxWVFQPI42JgOeR5h9UhTWUdYqUvNNa9zGAziScQWFhaDtKEiPGcFZYxy%2FsiYGIwEz9NhyV9Icu9dZzT4u9jcqTWQ5OkoLVZMDorEF9L%2FdvO%2FGnkUQqan1T%2FJvowfQNPD9O%2FXmH4C&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%2Frsa-sha256&Signature=teywNzhLQzLDDidFi9riYEwbIhNZbf0S6qdnosna3lpgR%2Fl6dcaVFGbq%2F2A6%2FKSjeWpDWx68QSdA%0A9DWicCZHaB0nhLZll4RP9W8H4Ok7fWb4oeNf6j3wot7JSma%2FNOJUXyyHQPa3bwOEnn3lCWSWnzbx%0AqIM8mDd2COq0Ix1l5QMQ8qAzIKQ7pG1ep3J0twlzkfP8Oy4OGBSdnxcbrJ8ZEReeHjY7aHOnH1Cb%0AeqZD3Mv67dXEje2ohTiS50izbrI%2FAD9uHvwKSR%2BLVBWIdeRx3j1RZ%2F99ZJhN8sMxhNVfkbEXgBo%2B%0AF74YZnqbXVtJY52ZUMN3o%2BzTWmKjEVLq2e09Qw%3D%3D
96
2014-11-22 12:58:08,319 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
97
2014-11-22 12:58:08,320 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
98
2014-11-22 12:58:08,649 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
99
  GET /gov_autent/async?SAMLRequest=jZJbS8MwGIb%2FSsl92iZ23RbWjakIwwMynYI3kiYfW9iazHxp0X9vqhvqjQxyk%2BPz8j6ZzN6bXdKBR%2BNsRViakwSsctrYdUVWj1d0RGbTCcpmx%2Fdi3oaNXcJbCxiSeNGi%2BN6pSOutcBINCisbQBGUeJjf3gie5mLvXXDK7UgyRwQfIurCWWwb8A%2FgO6NgtbypyCaEPYosC%2FU2hbWjTZdqyM5b8Gvw184G9wx11r%2B6BAWmg%2F7yDsIMAfv4C13xkqlcjwpalLWmBegBHathSSWrz6AENlSsIMlljG%2Bs7HP8UGNIna5drMKabYsUjO7xceVVtgFsyCR%2BWEWSxWVFQPI42JgOeR5h9UhTWUdYqUvNNa9zGAziScQWFhaDtKEiPGcFZYxy%2FsiYGIwEz9NhyV9Icu9dZzT4u9jcqTWQ5OkoLVZMDorEF9L%2FdvO%2FGnkUQqan1T%2FJvowfQNPD9O%2FXmH4C&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%2Frsa-sha256&Signature=teywNzhLQzLDDidFi9riYEwbIhNZbf0S6qdnosna3lpgR%2Fl6dcaVFGbq%2F2A6%2FKSjeWpDWx68QSdA%0A9DWicCZHaB0nhLZll4RP9W8H4Ok7fWb4oeNf6j3wot7JSma%2FNOJUXyyHQPa3bwOEnn3lCWSWnzbx%0AqIM8mDd2COq0Ix1l5QMQ8qAzIKQ7pG1ep3J0twlzkfP8Oy4OGBSdnxcbrJ8ZEReeHjY7aHOnH1Cb%0AeqZD3Mv67dXEje2ohTiS50izbrI%2FAD9uHvwKSR%2BLVBWIdeRx3j1RZ%2F99ZJhN8sMxhNVfkbEXgBo%2B%0AF74YZnqbXVtJY52ZUMN3o%2BzTWmKjEVLq2e09Qw%3D%3D HTTP/1.1
100
  Connection: keep-alive
101
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
102
  Host: prod.governikus-eid.de
103
  Accept: text/xml, */*;q=0.8
104
  Accept-Charset: utf-8, *;q=0.8
105

    
106
2014-11-22 12:58:08,729 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
107
  HTTP/1.1 302 Moved Temporarily
108
  Server: Apache-Coyote/1.1
109
  Content-Security-Policy: default-src 'self'
110
  Cache-Control: no-cache, no-store
111
  Pragma: no-cache
112
  Location: https://prod.governikus-eid.de/ecardpaos/startauthentication?DateOfBirth=true&GivenNames=true&idProviderUrl=https%3A%2F%2Fprod.governikus-eid.de%2Fgov_autent%2Fasync%3FrefID%3D6607f485f04120162ed46d482f7a72e35453d8ae&receiverUrl=https%3A%2F%2Fprodpaos.governikus-eid.de%3A443%2Fecardpaos%2Fpaosreceiver&requestID=ae1836b5-fcdf-4192-bd69-63983db84a3e&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&FamilyNames=true&PlaceOfResidence=true&Signature=O1FfXNVtNvXVNdEHcJ58eRGz6d2fYoOrOGBtiC8Ge%2FFNAwLu8xmgegFBmD0D2CamCwiNNOKI1pZh%0AakK3J%2B40C%2BDH4H2Gy1v36CIzTzQdU4bFbHFn0eoJ0WPOnP7ElPuTlYxIuqtxMjGDBUxpPVhVgHH0%0AqH0wYbwsdlEQ2ITfRQ0tssXk41wilsysww3YkDbvRYRF0JCjxslF0A79npfnnCSIZqkEdf4Ph5q7%0ABMj2XjINcde5drkM0rR5ZAZob%2FgTuvM3t0WKsj7Jscgko%2Bb61OhKUO2ME%2FDlW0LWS8ikhgLhiFU0%0AQNQDTAW1TxPrSHs9jIeioCJai3QJzRVSrK7VNA%3D%3D
113
  Content-Length: 0
114
  Date: Sat, 22 Nov 2014 11:58:12 GMT
115

    
116

    
117
2014-11-22 12:58:08,730 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://prod.governikus-eid.de/ecardpaos/startauthentication?DateOfBirth=true&GivenNames=true&idProviderUrl=https%3A%2F%2Fprod.governikus-eid.de%2Fgov_autent%2Fasync%3FrefID%3D6607f485f04120162ed46d482f7a72e35453d8ae&receiverUrl=https%3A%2F%2Fprodpaos.governikus-eid.de%3A443%2Fecardpaos%2Fpaosreceiver&requestID=ae1836b5-fcdf-4192-bd69-63983db84a3e&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&FamilyNames=true&PlaceOfResidence=true&Signature=O1FfXNVtNvXVNdEHcJ58eRGz6d2fYoOrOGBtiC8Ge%2FFNAwLu8xmgegFBmD0D2CamCwiNNOKI1pZh%0AakK3J%2B40C%2BDH4H2Gy1v36CIzTzQdU4bFbHFn0eoJ0WPOnP7ElPuTlYxIuqtxMjGDBUxpPVhVgHH0%0AqH0wYbwsdlEQ2ITfRQ0tssXk41wilsysww3YkDbvRYRF0JCjxslF0A79npfnnCSIZqkEdf4Ph5q7%0ABMj2XjINcde5drkM0rR5ZAZob%2FgTuvM3t0WKsj7Jscgko%2Bb61OhKUO2ME%2FDlW0LWS8ikhgLhiFU0%0AQNQDTAW1TxPrSHs9jIeioCJai3QJzRVSrK7VNA%3D%3D
118
2014-11-22 12:58:08,730 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
119
2014-11-22 12:58:08,731 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
120
2014-11-22 12:58:08,973 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
121
  GET /ecardpaos/startauthentication?DateOfBirth=true&GivenNames=true&idProviderUrl=https%3A%2F%2Fprod.governikus-eid.de%2Fgov_autent%2Fasync%3FrefID%3D6607f485f04120162ed46d482f7a72e35453d8ae&receiverUrl=https%3A%2F%2Fprodpaos.governikus-eid.de%3A443%2Fecardpaos%2Fpaosreceiver&requestID=ae1836b5-fcdf-4192-bd69-63983db84a3e&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&FamilyNames=true&PlaceOfResidence=true&Signature=O1FfXNVtNvXVNdEHcJ58eRGz6d2fYoOrOGBtiC8Ge%2FFNAwLu8xmgegFBmD0D2CamCwiNNOKI1pZh%0AakK3J%2B40C%2BDH4H2Gy1v36CIzTzQdU4bFbHFn0eoJ0WPOnP7ElPuTlYxIuqtxMjGDBUxpPVhVgHH0%0AqH0wYbwsdlEQ2ITfRQ0tssXk41wilsysww3YkDbvRYRF0JCjxslF0A79npfnnCSIZqkEdf4Ph5q7%0ABMj2XjINcde5drkM0rR5ZAZob%2FgTuvM3t0WKsj7Jscgko%2Bb61OhKUO2ME%2FDlW0LWS8ikhgLhiFU0%0AQNQDTAW1TxPrSHs9jIeioCJai3QJzRVSrK7VNA%3D%3D HTTP/1.1
122
  Connection: keep-alive
123
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
124
  Host: prod.governikus-eid.de
125
  Accept: text/xml, */*;q=0.8
126
  Accept-Charset: utf-8, *;q=0.8
127

    
128
2014-11-22 12:58:09,024 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
129
  HTTP/1.1 200 OK
130
  Server: Apache-Coyote/1.1
131
  Content-Security-Policy: default-src 'self'
132
  Content-Type: text/xml;charset=utf-8
133
  Content-Length: 628
134
  Date: Sat, 22 Nov 2014 11:58:12 GMT
135

    
136

    
137
2014-11-22 12:58:09,027 [Thread-5] DEBUG o.o.binding.tctoken.TCTokenContext:90 - Cleaned up TCToken:
138
<TCTokenType>
139
<ServerAddress>https://prodpaos.governikus-eid.de:443/ecardpaos/paosreceiver</ServerAddress>
140
<SessionIdentifier>ae1836b5-fcdf-4192-bd69-63983db84a3e</SessionIdentifier>
141
<RefreshAddress>https://prod.governikus-eid.de/gov_autent/async?refID=6607f485f04120162ed46d482f7a72e35453d8ae</RefreshAddress>
142
<Binding>urn:liberty:paos:2006-08</Binding>
143
<PathSecurity-Protocol>urn:ietf:rfc:4279</PathSecurity-Protocol>
144
<PathSecurity-Parameters>
145
<PSK>6E597E27812B74506529BEFD586974114E397A3496F754D10EC16C38BE05D472B3F011A0A6A7A359D938A031B842ECB38F76FF03D23B02CAA041169C4F097B65</PSK>
146
</PathSecurity-Parameters>
147
</TCTokenType>
148

    
149
2014-11-22 12:58:09,053 [Thread-5] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationPath
150
2014-11-22 12:58:09,055 [Thread-5] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
151
2014-11-22 12:58:09,055 [Thread-5] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
152
2014-11-22 12:58:09,073 [Thread-5] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
153
2014-11-22 12:58:09,074 [Thread-5] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
154
2014-11-22 12:58:09,097 [Thread-5] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
155
2014-11-22 12:58:09,129 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
156
2014-11-22 12:58:09,129 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
157
2014-11-22 12:58:09,513 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
158
  POST /ecardpaos/paosreceiver HTTP/1.1
159
  Connection: keep-alive
160
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
161
  Host: prodpaos.governikus-eid.de:443
162
  PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit';"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework';"urn:iso:std:iso-iec:24727:tech:schema:Hash';"urn:iso:std:iso-iec:24727:tech:schema:ACLList';"urn:iso:std:iso-iec:24727:tech:schema:DIDGet';"urn:iso:std:iso-iec:24727:tech:schema:DIDList';"urn:iso:std:iso-iec:24727:tech:schema:DSIList';"urn:iso:std:iso-iec:24727:tech:schema:DSIRead';"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite';"urn:iso:std:iso-iec:24727:tech:schema:Encipher';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect';"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate';"urn:iso:std:iso-iec:24727:tech:schema:GetRandom';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList';"urn:iso:std:iso-iec:24727:tech:schema:StartSession'
163
  Accept: text/xml, application/xml, application/vnd.paos+xml
164

    
165
2014-11-22 12:58:09,544 [PAOS] DEBUG org.openecard.transport.paos.PAOS:228 - Message sent:
166
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
167
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
168
  <Header>
169
    <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
170
      <Version>urn:liberty:paos:2006-08</Version>
171
      <EndpointReference>
172
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
173
        <MetaData>
174
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
175
        </MetaData>
176
      </EndpointReference>
177
    </PAOS>
178
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
179
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
180
    </ReplyTo>
181
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:527db027-617e-413f-94fc-6562395dff88</MessageID>
182
  </Header>
183
  <Body>
184
    <iso:StartPAOS xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns11="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns12="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns13="http://www.w3.org/2001/04/xmlenc#" xmlns:ns14="http://ws.openecard.org/schema" xmlns:ns15="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns16="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="http://uri.etsi.org/02231/v2.1.1#" xmlns:ns6="http://uri.etsi.org/02231/v2.x#" xmlns:ns7="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns8="http://www.setcce.org/schemas/ers" xmlns:ns9="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#" Profile="http://www.bsi.bund.de/ecard/api/1.1">
185
      <iso:SessionIdentifier>ae1836b5-fcdf-4192-bd69-63983db84a3e</iso:SessionIdentifier>
186
      <iso:ConnectionHandle>
187
        <iso:ChannelHandle>
188
          <iso:SessionIdentifier>dS1Z02IQbRAPkgm0xvkpig</iso:SessionIdentifier>
189
        </iso:ChannelHandle>
190
        <iso:ContextHandle>CE8CC1C8BB660931A6428F4833D5C668</iso:ContextHandle>
191
        <iso:IFDName>REINER SCT cyberJack RFID basis 0</iso:IFDName>
192
        <iso:SlotIndex>0</iso:SlotIndex>
193
        <iso:CardApplication>3F00</iso:CardApplication>
194
        <iso:SlotHandle>B84FBB0CE5616F3055F2FFB1A91DCACD</iso:SlotHandle>
195
        <iso:RecognitionInfo>
196
          <iso:CardType>http://bsi.bund.de/cif/npa.xml</iso:CardType>
197
        </iso:RecognitionInfo>
198
      </iso:ConnectionHandle>
199
      <iso:UserAgent>
200
        <iso:Name>Open eCard App</iso:Name>
201
        <iso:VersionMajor>1</iso:VersionMajor>
202
        <iso:VersionMinor>1</iso:VersionMinor>
203
        <iso:VersionSubminor>0</iso:VersionSubminor>
204
      </iso:UserAgent>
205
      <iso:SupportedAPIVersions>
206
        <iso:Major>1</iso:Major>
207
        <iso:Minor>1</iso:Minor>
208
        <iso:Subminor>4</iso:Subminor>
209
      </iso:SupportedAPIVersions>
210
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14</iso:SupportedDIDProtocols>
211
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14.2</iso:SupportedDIDProtocols>
212
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.25</iso:SupportedDIDProtocols>
213
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.9</iso:SupportedDIDProtocols>
214
    </iso:StartPAOS>
215
  </Body>
216
</Envelope>
217

    
218
2014-11-22 12:58:09,690 [PAOS] WARN  org.openecard.transport.paos.PAOS:399 - The PAOS endpoint sent the http status code 200 which does not conform to thePAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
219
2014-11-22 12:58:09,692 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
220
  HTTP/1.1 200 OK
221
  Server: Apache-Coyote/1.1
222
  Content-Security-Policy: default-src 'self'
223
  Content-Type: application/vnd.paos+xml;charset=ISO-8859-1
224
  Transfer-Encoding: chunked
225
  Date: Sat, 22 Nov 2014 11:58:12 GMT
226
<?xml version="1.0" encoding="UTF-8"?><soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
227
   <soap:Header>
228
      <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8468" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
229
      <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:527db027-617e-413f-94fc-6562395dff88</RelatesTo>
230
      <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:369a915c-a367-4f7f-89b8-7bb8f6587f77</MessageID>
231
   </soap:Header>
232
   <soap:Body>
233
   <InitializeFramework xmlns="http://www.bsi.bund.de/ecard/api/1.1"/>
234
</soap:Body>
235
</soap:Envelope>
236

    
237

    
238
2014-11-22 12:58:09,695 [PAOS] DEBUG org.openecard.transport.paos.PAOS:208 - Message received:
239
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
240
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
241
  <soap:Header>
242
    <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8468" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
243
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:527db027-617e-413f-94fc-6562395dff88</RelatesTo>
244
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:369a915c-a367-4f7f-89b8-7bb8f6587f77</MessageID>
245
  </soap:Header>
246
  <soap:Body>
247
    <InitializeFramework xmlns="http://www.bsi.bund.de/ecard/api/1.1"/>
248
  </soap:Body>
249
</soap:Envelope>
250

    
251
2014-11-22 12:58:09,696 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: de.bund.bsi.ecard.api._1.InitializeFramework
252
2014-11-22 12:58:09,698 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
253
  POST /ecardpaos/paosreceiver HTTP/1.1
254
  Connection: keep-alive
255
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
256
  Host: prodpaos.governikus-eid.de:443
257
  PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit';"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework';"urn:iso:std:iso-iec:24727:tech:schema:Hash';"urn:iso:std:iso-iec:24727:tech:schema:ACLList';"urn:iso:std:iso-iec:24727:tech:schema:DIDGet';"urn:iso:std:iso-iec:24727:tech:schema:DIDList';"urn:iso:std:iso-iec:24727:tech:schema:DSIList';"urn:iso:std:iso-iec:24727:tech:schema:DSIRead';"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite';"urn:iso:std:iso-iec:24727:tech:schema:Encipher';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect';"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate';"urn:iso:std:iso-iec:24727:tech:schema:GetRandom';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList';"urn:iso:std:iso-iec:24727:tech:schema:StartSession'
258
  Accept: text/xml, application/xml, application/vnd.paos+xml
259

    
260
2014-11-22 12:58:09,703 [PAOS] DEBUG org.openecard.transport.paos.PAOS:228 - Message sent:
261
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
262
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
263
  <Header>
264
    <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
265
      <Version>urn:liberty:paos:2006-08</Version>
266
      <EndpointReference>
267
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
268
        <MetaData>
269
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
270
        </MetaData>
271
      </EndpointReference>
272
    </PAOS>
273
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
274
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
275
    </ReplyTo>
276
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:369a915c-a367-4f7f-89b8-7bb8f6587f77</RelatesTo>
277
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:60f6ed95-ebef-47ef-a1a9-4f00f93f14be</MessageID>
278
  </Header>
279
  <Body>
280
    <ns2:InitializeFrameworkResponse xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns11="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns12="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns13="http://www.w3.org/2001/04/xmlenc#" xmlns:ns14="http://ws.openecard.org/schema" xmlns:ns15="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns16="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="http://uri.etsi.org/02231/v2.1.1#" xmlns:ns6="http://uri.etsi.org/02231/v2.x#" xmlns:ns7="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns8="http://www.setcce.org/schemas/ers" xmlns:ns9="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#">
281
      <ns3:Result>
282
        <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#ok</ns3:ResultMajor>
283
      </ns3:Result>
284
      <ns2:Version>
285
        <ns2:Major>1</ns2:Major>
286
        <ns2:Minor>1</ns2:Minor>
287
        <ns2:SubMinor>4</ns2:SubMinor>
288
      </ns2:Version>
289
    </ns2:InitializeFrameworkResponse>
290
  </Body>
291
</Envelope>
292

    
293
2014-11-22 12:58:09,793 [PAOS] WARN  org.openecard.transport.paos.PAOS:399 - The PAOS endpoint sent the http status code 200 which does not conform to thePAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
294
2014-11-22 12:58:09,795 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
295
  HTTP/1.1 200 OK
296
  Server: Apache-Coyote/1.1
297
  Content-Security-Policy: default-src 'self'
298
  Content-Type: application/vnd.paos+xml;charset=ISO-8859-1
299
  Transfer-Encoding: chunked
300
  Date: Sat, 22 Nov 2014 11:58:13 GMT
301
<?xml version="1.0" encoding="UTF-8"?><soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
302
   <soap:Header>
303
      <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8469" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
304
      <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:60f6ed95-ebef-47ef-a1a9-4f00f93f14be</RelatesTo>
305
      <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:e615dee1-b19b-4a66-8658-d0b5f86f3c40</MessageID>
306
   </soap:Header>
307
   <soap:Body>
308
   <DIDAuthenticate xmlns="urn:iso:std:iso-iec:24727:tech:schema">
309
<ConnectionHandle xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="ConnectionHandle">
310
<ChannelHandle>
311
<SessionIdentifier>dS1Z02IQbRAPkgm0xvkpig</SessionIdentifier>
312
</ChannelHandle>
313
<ContextHandle>CE8CC1C8BB660931A6428F4833D5C668</ContextHandle>
314
<IFDName>REINER SCT cyberJack RFID basis 0</IFDName>
315
<SlotIndex>0</SlotIndex>
316
<CardApplication>3F00</CardApplication>
317
<SlotHandle>B84FBB0CE5616F3055F2FFB1A91DCACD</SlotHandle>
318
<RecognitionInfo>
319
<CardType>http://bsi.bund.de/cif/npa.xml</CardType>
320
</RecognitionInfo>
321
</ConnectionHandle>
322
<DIDName>PIN</DIDName>
323
<AuthenticationProtocolData Protocol="urn:oid:1.3.162.15480.3.0.14.2" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="EAC1InputType">
324
<Certificate>7F218201487F4E8201005F2901004210444544566549444454523130313431357F494F060A04007F0007020202020386410467E926AF24053149B949E1B1CE543BB2D4E48A5389CFEBADEAD37D68E4BED1705B85124F2B41EA0E6D4A47B3F3CC74A20586047A4E1096BD84230FB31FB065D55F200E44453030303031333630303434307F4C12060904007F00070301020253050001029B005F25060104010102025F2406010401010203655E732D060904007F0007030103028020B92C59DEE2AF301AC9095E7FE7FCE5441B2A52E204C6F12074F8B92D15A1124F732D060904007F00070301030180205567F538866593B65C2CB45285593AFFCF15B796A9B4315945FD71F16C7B62DE5F37404BA3060E647EFE4D7639D88C1925EB09834FC8B46C42F62414813318608DA08C772135C2B2385CAC5745262D4355768E951F1CA0128E70BE57D400F9BD0862A0</Certificate>
325
<CertificateDescription>30820364060A04007F00070301030101A10E0C0C442D547275737420476D6248A2181316687474703A2F2F7777772E642D74727573742E6E6574A3430C415A7765636B76657262616E642022456C656B74726F6E69736368652056657277616C74756E6720696E204D65636B6C656E627572672D566F72706F6D6D65726E22A427132568747470733A2F2F74626B2E65676F2D6D762E64652F427565726765724B6F6E746F576562A582022E0C82022A4E616D652C20416E7363687269667420756E6420452D4D61696C2D4164726573736520646573204469656E737465616E626965746572733A0D0A5A7765636B76657262616E642022456C656B74726F6E69736368652056657277616C74756E6720696E204D65636B6C656E627572672D566F72706F6D6D65726E220D0A45636B6472696674203130330D0A313930363120536368776572696E0D0A696E666F4065676F2D6D762E64650D0A0D0A4765736368C3A46674737A7765636B3A0D0A2D2041627769636B6C756E6720766F6E20416E77656E64756E67656E206D6974204964656E746966696B6174696F6E7362656461726620696E2064657220C3B66666656E746C696368656E2056657277616C74756E67202874656D706F72C3A47265732042C3BC726765726B6F6E746F29202D0D0A0D0A5A757374C3A46E6469676520446174656E73636875747A626568C3B67264653A0D0A446572204C616E64657362656175667472616774650D0A66C3BC7220446174656E73636875747A20756E6420496E666F726D6174696F6E736672656968656974204D65636B6C656E627572672D0D0A566F72706F6D6D65726E0D0A4C656E6E6573747261C39F6520312C205363686C6F737320536368776572696E0D0A313930353320536368776572696E0D0A54656C65666F6E3A20303338352035393439342D300D0A54656C656661783A20303338352035393439342D35380D0A696E666F40646174656E73636875747A2D6D762E6465A7818B318188042029F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F40420561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB304209D8EB0BDF36B19C4AF3147E57401FD792845F0413102A7BD784DF9418098BEBE0420EBD2A9610AA53AE1C159B2C7B238451E2C6AF06F7034723BD4B9743196E6A720</CertificateDescription>
326
<RequiredCHAT>7F4C12060904007F00070301020253050001009800</RequiredCHAT>
327
<OptionalCHAT>7F4C12060904007F00070301020253050000000000</OptionalCHAT>
328
<AuthenticatedAuxiliaryData>67177315060904007F00070301040253083230313431313232</AuthenticatedAuxiliaryData>
329
</AuthenticationProtocolData>
330
</DIDAuthenticate>
331
</soap:Body>
332
</soap:Envelope>
333

    
334

    
335
2014-11-22 12:58:09,798 [PAOS] DEBUG org.openecard.transport.paos.PAOS:208 - Message received:
336
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
337
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
338
  <soap:Header>
339
    <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8469" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
340
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:60f6ed95-ebef-47ef-a1a9-4f00f93f14be</RelatesTo>
341
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:e615dee1-b19b-4a66-8658-d0b5f86f3c40</MessageID>
342
  </soap:Header>
343
  <soap:Body>
344
    <DIDAuthenticate xmlns="urn:iso:std:iso-iec:24727:tech:schema">
345
      <ConnectionHandle xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="ConnectionHandle">
346
        <ChannelHandle>
347
          <SessionIdentifier>dS1Z02IQbRAPkgm0xvkpig</SessionIdentifier>
348
        </ChannelHandle>
349
        <ContextHandle>CE8CC1C8BB660931A6428F4833D5C668</ContextHandle>
350
        <IFDName>REINER SCT cyberJack RFID basis 0</IFDName>
351
        <SlotIndex>0</SlotIndex>
352
        <CardApplication>3F00</CardApplication>
353
        <SlotHandle>B84FBB0CE5616F3055F2FFB1A91DCACD</SlotHandle>
354
        <RecognitionInfo>
355
          <CardType>http://bsi.bund.de/cif/npa.xml</CardType>
356
        </RecognitionInfo>
357
      </ConnectionHandle>
358
      <DIDName>PIN</DIDName>
359
      <AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="EAC1InputType">
360
        <Certificate>7F218201487F4E8201005F2901004210444544566549444454523130313431357F494F060A04007F0007020202020386410467E926AF24053149B949E1B1CE543BB2D4E48A5389CFEBADEAD37D68E4BED1705B85124F2B41EA0E6D4A47B3F3CC74A20586047A4E1096BD84230FB31FB065D55F200E44453030303031333630303434307F4C12060904007F00070301020253050001029B005F25060104010102025F2406010401010203655E732D060904007F0007030103028020B92C59DEE2AF301AC9095E7FE7FCE5441B2A52E204C6F12074F8B92D15A1124F732D060904007F00070301030180205567F538866593B65C2CB45285593AFFCF15B796A9B4315945FD71F16C7B62DE5F37404BA3060E647EFE4D7639D88C1925EB09834FC8B46C42F62414813318608DA08C772135C2B2385CAC5745262D4355768E951F1CA0128E70BE57D400F9BD0862A0</Certificate>
361
        <CertificateDescription>30820364060A04007F00070301030101A10E0C0C442D547275737420476D6248A2181316687474703A2F2F7777772E642D74727573742E6E6574A3430C415A7765636B76657262616E642022456C656B74726F6E69736368652056657277616C74756E6720696E204D65636B6C656E627572672D566F72706F6D6D65726E22A427132568747470733A2F2F74626B2E65676F2D6D762E64652F427565726765724B6F6E746F576562A582022E0C82022A4E616D652C20416E7363687269667420756E6420452D4D61696C2D4164726573736520646573204469656E737465616E626965746572733A0D0A5A7765636B76657262616E642022456C656B74726F6E69736368652056657277616C74756E6720696E204D65636B6C656E627572672D566F72706F6D6D65726E220D0A45636B6472696674203130330D0A313930363120536368776572696E0D0A696E666F4065676F2D6D762E64650D0A0D0A4765736368C3A46674737A7765636B3A0D0A2D2041627769636B6C756E6720766F6E20416E77656E64756E67656E206D6974204964656E746966696B6174696F6E7362656461726620696E2064657220C3B66666656E746C696368656E2056657277616C74756E67202874656D706F72C3A47265732042C3BC726765726B6F6E746F29202D0D0A0D0A5A757374C3A46E6469676520446174656E73636875747A626568C3B67264653A0D0A446572204C616E64657362656175667472616774650D0A66C3BC7220446174656E73636875747A20756E6420496E666F726D6174696F6E736672656968656974204D65636B6C656E627572672D0D0A566F72706F6D6D65726E0D0A4C656E6E6573747261C39F6520312C205363686C6F737320536368776572696E0D0A313930353320536368776572696E0D0A54656C65666F6E3A20303338352035393439342D300D0A54656C656661783A20303338352035393439342D35380D0A696E666F40646174656E73636875747A2D6D762E6465A7818B318188042029F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F40420561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB304209D8EB0BDF36B19C4AF3147E57401FD792845F0413102A7BD784DF9418098BEBE0420EBD2A9610AA53AE1C159B2C7B238451E2C6AF06F7034723BD4B9743196E6A720</CertificateDescription>
362
        <RequiredCHAT>7F4C12060904007F00070301020253050001009800</RequiredCHAT>
363
        <OptionalCHAT>7F4C12060904007F00070301020253050000000000</OptionalCHAT>
364
        <AuthenticatedAuxiliaryData>67177315060904007F00070301040253083230313431313232</AuthenticatedAuxiliaryData>
365
      </AuthenticationProtocolData>
366
    </DIDAuthenticate>
367
  </soap:Body>
368
</soap:Envelope>
369

    
370
2014-11-22 12:58:09,804 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDAuthenticate
371
2014-11-22 12:58:09,820 [PAOS] WARN  org.openecard.common.I18n:151 - Failed to load resource eac for lang de_DE.
372
2014-11-22 12:58:09,822 [PAOS] WARN  org.openecard.common.I18n:151 - Failed to load resource pace for lang de_DE.
373
2014-11-22 12:58:09,839 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
374
2014-11-22 12:58:09,846 [PAOS] WARN  o.openecard.ifd.scio.wrapper.SCCard:80 - Unable to request features from reader.
375
javax.smartcardio.CardException: transmitControlCommand() failed
376
	at sun.security.smartcardio.CardImpl.transmitControlCommand(Unknown Source) ~[na:1.8.0_25]
377
	at org.openecard.ifd.scio.wrapper.SCCard.controlCommand(SCCard.java:67) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
378
	at org.openecard.ifd.scio.wrapper.SCCard.getFeatureCodes(SCCard.java:76) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
379
	at org.openecard.ifd.scio.wrapper.SCTerminal.getDisplayCapability(SCTerminal.java:235) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
380
	at org.openecard.ifd.scio.IFD.getIFDCapabilities(IFD.java:273) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
381
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_25]
382
	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
383
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
384
	at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_25]
385
	at org.openecard.transport.dispatcher.Service.invoke(Service.java:192) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
386
	at org.openecard.transport.dispatcher.MessageDispatcher.deliver(MessageDispatcher.java:98) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
387
	at org.openecard.sal.protocol.eac.PACEStep.genericPACESupport(PACEStep.java:465) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
388
	at org.openecard.sal.protocol.eac.PACEStep.perform(PACEStep.java:146) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
389
	at org.openecard.sal.protocol.eac.PACEStep.perform(PACEStep.java:96) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
390
	at org.openecard.addon.sal.SALProtocolBaseImpl.perform(SALProtocolBaseImpl.java:163) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
391
	at org.openecard.addon.sal.SALProtocolBaseImpl.didAuthenticate(SALProtocolBaseImpl.java:244) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
392
	at org.openecard.addon.sal.SALProtocolProxy.didAuthenticate(SALProtocolProxy.java:147) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
393
	at org.openecard.sal.TinySAL.didAuthenticate(TinySAL.java:1938) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
394
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_25]
395
	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
396
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
397
	at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_25]
398
	at org.openecard.transport.dispatcher.Service.invoke(Service.java:192) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
399
	at org.openecard.transport.dispatcher.MessageDispatcher.deliver(MessageDispatcher.java:98) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
400
	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:332) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
401
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:97) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
402
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:48) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
403
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
404
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
405
Caused by: sun.security.smartcardio.PCSCException: Unknown error 0x1
406
	at sun.security.smartcardio.PCSC.SCardControl(Native Method) ~[na:1.8.0_25]
407
	... 29 common frames omitted
408
2014-11-22 12:58:09,848 [PAOS] WARN  o.o.c.c.a.c.CardVerifiableCertificateChain:60 - Verification of the certificate chain is disabled.
409
2014-11-22 12:58:09,849 [PAOS] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 9D8EB0BDF36B19C4AF3147E57401FD792845F0413102A7BD784DF9418098BEBE
410
2014-11-22 12:58:09,850 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
411
2014-11-22 12:58:09,850 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
412
2014-11-22 12:58:09,851 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 9D8EB0BDF36B19C4AF3147E57401FD792845F0413102A7BD784DF9418098BEBE
413
2014-11-22 12:58:09,852 [PAOS] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
414
2014-11-22 12:58:09,852 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
415
2014-11-22 12:58:09,853 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
416
2014-11-22 12:58:09,853 [PAOS] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
417
2014-11-22 12:58:09,853 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
418
2014-11-22 12:58:09,854 [PAOS] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
419
2014-11-22 12:58:09,854 [PAOS] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
420
2014-11-22 12:58:09,855 [PAOS] DEBUG o.o.common.util.TR03112Utils:54 - Checking SOP for https://tbk.ego-mv.de/BuergerKontoWeb/SAMLRequestServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14 and https://tbk.ego-mv.de/BuergerKontoWeb.
421
2014-11-22 12:58:09,857 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
422
2014-11-22 12:58:09,858 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0x22 0xC1 0xA4 0x0F 0x80 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x04 0x02 0x02 0x83 0x01 0x03 
423
2014-11-22 12:58:09,890 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
424
2014-11-22 12:58:09,916 [EAC-GUI] WARN  org.openecard.common.I18n:151 - Failed to load resource gui for lang de_DE.
425
2014-11-22 12:58:09,944 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 0, previous was -1.
426
2014-11-22 12:58:14,334 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:238 - Received event: Button.next
427
2014-11-22 12:58:14,345 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:213 - Exchange result for step 'Anbieter
428
2014-11-22 12:58:14,363 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 1, previous was 0.
429
2014-11-22 12:58:16,085 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:238 - Received event: Button.next
430
2014-11-22 12:58:16,089 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:213 - Exchange result for step 'Angefragte Daten
431
2014-11-22 12:58:16,105 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 2, previous was 1.
432
2014-11-22 12:58:25,294 [AWT-EventQueue-0] DEBUG o.o.gui.swing.SwingNavigator:238 - Received event: Button.next
433
2014-11-22 12:58:25,298 [AWT-EventQueue-0] DEBUG org.openecard.gui.swing.StepFrame:213 - Exchange result for step 'PIN-Eingabe
434
2014-11-22 12:58:25,316 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EstablishChannel
435
2014-11-22 12:58:25,331 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
436
2014-11-22 12:58:25,384 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xA4 0x02 0x04 0x02 0x01 0x1C 0xFF 
437
2014-11-22 12:58:25,400 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x62 0x1E 0x80 0x02 0x00 0xB6 0x82 0x01 0x21 0x83 0x02 0x01 0x1C 0x88 0x01 0xE0 0x8A 0x01 0x05 0xAB 0x0B 0x84 0x01 0xB0 0x84 0x01 0xB1 0x84 0x01 0xA4 0x90 0x00 0x90 0x00 
438
2014-11-22 12:58:25,408 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
439
2014-11-22 12:58:25,409 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
440
2014-11-22 12:58:25,426 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x31 0x81 0xB3 0x30 0x0D 0x06 0x08 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x02 0x02 0x01 0x02 0x30 0x12 0x06 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x03 0x02 0x02 0x02 0x01 0x02 0x02 0x01 0x41 0x30 0x12 0x06 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x03 0x02 0x02 0x02 0x01 0x02 0x02 0x01 0x45 0x30 0x12 0x06 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x04 0x02 0x02 0x02 0x01 0x02 0x02 0x01 0x0D 0x30 0x1C 0x06 0x09 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x03 0x02 0x30 0x0C 0x06 0x07 0x04 0x00 0x7F 0x00 0x07 0x01 0x02 0x02 0x01 0x0D 0x02 0x01 0x41 0x30 0x1C 0x06 0x09 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x03 0x02 0x30 0x0C 0x06 0x07 0x04 0x00 0x7F 0x00 0x07 0x01 0x02 0x02 0x01 0x0D 0x02 0x01 0x45 0x30 0x2A 0x06 0x08 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x06 0x16 0x1E 0x68 0x74 0x74 0x70 0x3A 0x2F 0x2F 0x62 0x73 0x69 0x2E 0x62 0x75 0x6E 0x64 0x2E 0x64 0x65 0x2F 0x63 0x69 0x66 0x2F 0x6E 0x70 0x61 0x2E 0x78 0x6D 0x6C 0x62 0x82 
441
2014-11-22 12:58:25,439 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:117 - Found TerminalAuthenticationInfo object identifier
442
2014-11-22 12:58:25,441 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:107 - Found ChipAuthenticationInfo object identifier
443
2014-11-22 12:58:25,442 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:107 - Found ChipAuthenticationInfo object identifier
444
2014-11-22 12:58:25,442 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:97 - Found PACEInfo object identifier
445
2014-11-22 12:58:25,442 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:112 - Found ChipAuthenticationDomainParameterInfo object identifier
446
2014-11-22 12:58:25,444 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:112 - Found ChipAuthenticationDomainParameterInfo object identifier
447
2014-11-22 12:58:25,444 [pool-6-thread-1] DEBUG o.o.c.c.asn1.eac.ef.EFCardAccess:122 - Found CardInfoLocator object identifier
448
2014-11-22 12:58:25,479 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
449
2014-11-22 12:58:25,480 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0x22 0xC1 0xA4 0x24 0x80 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x04 0x02 0x02 0x83 0x01 0x03 0x7F 0x4C 0x12 0x06 0x09 0x04 0x00 0x7F 0x00 0x07 0x03 0x01 0x02 0x02 0x53 0x05 0x00 0x01 0x00 0x98 0x00 
450
2014-11-22 12:58:25,512 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x90 0x00 
451
2014-11-22 12:58:25,513 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
452
2014-11-22 12:58:25,513 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x10 0x86 0x00 0x00 0x02 0x7C 0x00 0x00 
453
2014-11-22 12:58:25,605 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x7C 0x12 0x80 0x10 0x4A 0x46 0x54 0x60 0x25 0x5F 0x5B 0xBC 0x85 0x30 0xAF 0xCD 0xC2 0x4A 0x64 0xB0 0x90 0x00 
454
2014-11-22 12:58:26,260 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
455
2014-11-22 12:58:26,260 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x10 0x86 0x00 0x00 0x45 0x7C 0x43 0x81 0x41 0x04 0x19 0xED 0x60 0x57 0xBA 0xB9 0x2C 0xD2 0x08 0x24 0x6D 0xA4 0x5E 0xC8 0x36 0x64 0xF3 0xC0 0xAD 0xD0 0x3F 0x5F 0x47 0xC0 0x78 0x35 0xF7 0x9F 0x87 0x8A 0xC4 0x2E 0x38 0xF2 0x88 0xD8 0x74 0x0D 0xAE 0x3F 0xFA 0x99 0xAB 0x77 0xEE 0xF8 0x38 0x84 0xF9 0x15 0x6C 0xB9 0x8C 0xCF 0xBE 0xE7 0x05 0x95 0xD9 0x33 0xC0 0xC6 0x73 0xAF 0x00 
456
2014-11-22 12:58:26,581 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x7C 0x43 0x82 0x41 0x04 0x12 0xF8 0x98 0xA0 0xE8 0x9F 0x29 0x3B 0x35 0x41 0x99 0x96 0x84 0xDD 0x97 0x43 0xF2 0x1E 0xBD 0x6F 0x27 0x5C 0x66 0x50 0xB9 0x08 0xCF 0xB9 0x1F 0x15 0x6B 0x46 0x47 0x64 0x34 0xEC 0x5F 0xD1 0x8D 0xAA 0xC4 0xF9 0x13 0x63 0x15 0x83 0xA7 0xE9 0x8C 0x35 0xFE 0xC0 0xDE 0xF1 0xFD 0x9D 0x58 0x47 0x56 0x35 0xD8 0xF0 0x07 0x53 0x90 0x00 
457
2014-11-22 12:58:26,614 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
458
2014-11-22 12:58:26,615 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x10 0x86 0x00 0x00 0x45 0x7C 0x43 0x83 0x41 0x04 0x92 0x76 0x41 0xBA 0x84 0x73 0xC7 0xB7 0xD5 0x1A 0xC3 0x55 0x9C 0x3E 0x8B 0x6B 0x6D 0xFA 0xCB 0xF8 0x2A 0xAA 0x6B 0x79 0x49 0x2B 0xAF 0x1A 0x4A 0x7B 0x7C 0x7F 0x85 0xBF 0xF6 0x24 0x87 0x7C 0x19 0xD9 0xA9 0xD7 0xE1 0x9D 0x90 0x0C 0xA4 0xCC 0x86 0x78 0x25 0xB0 0xC3 0xB7 0x1A 0xCE 0x0C 0xB8 0xF9 0x60 0xF5 0x28 0x55 0x44 0x00 
459
2014-11-22 12:58:26,841 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x7C 0x43 0x84 0x41 0x04 0x9C 0xB2 0x97 0x5C 0x72 0x06 0x62 0x49 0xDE 0x05 0x33 0x09 0x3E 0x70 0xB6 0x2F 0x82 0xD4 0x0F 0x7C 0xC6 0x9A 0x0F 0x50 0x2A 0xE7 0xC3 0x3D 0x05 0x43 0x54 0x0D 0x8A 0x19 0x34 0x87 0x42 0x29 0x68 0x74 0x5C 0x98 0x08 0x13 0xAA 0x4C 0x35 0x04 0x02 0x51 0xD3 0x84 0xC5 0x88 0x10 0x44 0xE7 0x10 0x26 0xAF 0x74 0x8D 0x9E 0x6B 0x90 0x00 
460
2014-11-22 12:58:26,875 [pool-6-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
461
2014-11-22 12:58:26,875 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x00 0x86 0x00 0x00 0x0C 0x7C 0x0A 0x85 0x08 0x1F 0x5A 0x7B 0x3A 0x82 0x0C 0x3C 0x31 0x00 
462
2014-11-22 12:58:27,068 [pool-6-thread-1] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x7C 0x2A 0x86 0x08 0x83 0x84 0x01 0xA9 0xAB 0x6E 0x6F 0xEA 0x87 0x0E 0x44 0x45 0x43 0x56 0x43 0x41 0x65 0x49 0x44 0x30 0x30 0x31 0x30 0x33 0x88 0x0E 0x44 0x45 0x43 0x56 0x43 0x41 0x65 0x49 0x44 0x30 0x30 0x31 0x30 0x32 0x90 0x00 
463
2014-11-22 12:58:27,070 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:97 - Selecting index 3, previous was 2.
464
2014-11-22 12:58:27,072 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
465
2014-11-22 12:58:27,072 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:78 - Apply secure messaging to APDU: 0x00 0x84 0x00 0x00 0x08 
466
2014-11-22 12:58:27,075 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:81 - Send APDU: 0x0C 0x84 0x00 0x00 0x0D 0x97 0x01 0x08 0x8E 0x08 0x1C 0x89 0x09 0x30 0x1C 0x87 0x87 0xAA 0x00 
467
2014-11-22 12:58:27,080 [Instant-Return-Thread] DEBUG o.o.gui.swing.SwingNavigator:238 - Received event: Button.next
468
2014-11-22 12:58:27,081 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:213 - Exchange result for step 'Bearbeitung
469
2014-11-22 12:58:27,116 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:85 - Receive APDU: 0x87 0x11 0x01 0xE9 0xDB 0x9D 0xF6 0xBA 0x00 0x77 0xDB 0xB0 0xEE 0x66 0x6F 0xEF 0x8F 0xFB 0x98 0x99 0x02 0x90 0x00 0x8E 0x08 0x28 0x98 0x3F 0x18 0x76 0xD0 0x91 0xDE 0x90 0x00 
470
2014-11-22 12:58:27,117 [PAOS] DEBUG o.o.ifd.scio.wrapper.SCChannel:88 - Remove secure messaging from APDU: 0xDF 0xDF 0x4C 0x39 0x81 0x13 0x77 0xCC 0x90 0x00 
471
2014-11-22 12:58:27,118 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
472
  POST /ecardpaos/paosreceiver HTTP/1.1
473
  Connection: keep-alive
474
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
475
  Host: prodpaos.governikus-eid.de:443
476
  PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit';"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework';"urn:iso:std:iso-iec:24727:tech:schema:Hash';"urn:iso:std:iso-iec:24727:tech:schema:ACLList';"urn:iso:std:iso-iec:24727:tech:schema:DIDGet';"urn:iso:std:iso-iec:24727:tech:schema:DIDList';"urn:iso:std:iso-iec:24727:tech:schema:DSIList';"urn:iso:std:iso-iec:24727:tech:schema:DSIRead';"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite';"urn:iso:std:iso-iec:24727:tech:schema:Encipher';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect';"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate';"urn:iso:std:iso-iec:24727:tech:schema:GetRandom';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList';"urn:iso:std:iso-iec:24727:tech:schema:StartSession'
477
  Accept: text/xml, application/xml, application/vnd.paos+xml
478

    
479
2014-11-22 12:58:27,120 [PAOS] DEBUG org.openecard.transport.paos.PAOS:228 - Message sent:
480
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
481
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
482
  <Header>
483
    <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
484
      <Version>urn:liberty:paos:2006-08</Version>
485
      <EndpointReference>
486
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
487
        <MetaData>
488
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
489
        </MetaData>
490
      </EndpointReference>
491
    </PAOS>
492
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
493
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
494
    </ReplyTo>
495
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:e615dee1-b19b-4a66-8658-d0b5f86f3c40</RelatesTo>
496
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a9210348-708c-4631-8930-60df978a2427</MessageID>
497
  </Header>
498
  <Body>
499
    <iso:DIDAuthenticateResponse xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns11="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns12="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns13="http://www.w3.org/2001/04/xmlenc#" xmlns:ns14="http://ws.openecard.org/schema" xmlns:ns15="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns16="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="http://uri.etsi.org/02231/v2.1.1#" xmlns:ns6="http://uri.etsi.org/02231/v2.x#" xmlns:ns7="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns8="http://www.setcce.org/schemas/ers" xmlns:ns9="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#">
500
      <ns3:Result>
501
        <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#ok</ns3:ResultMajor>
502
      </ns3:Result>
503
      <iso:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="iso:EAC1OutputType">
504
        <iso:CertificateHolderAuthorizationTemplate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7F4C12060904007F00070301020253050001009800</iso:CertificateHolderAuthorizationTemplate>
505
        <iso:CertificationAuthorityReference xmlns="urn:iso:std:iso-iec:24727:tech:schema">DECVCAeID00103</iso:CertificationAuthorityReference>
506
        <iso:EFCardAccess xmlns="urn:iso:std:iso-iec:24727:tech:schema">3181B3300D060804007F00070202020201023012060A04007F000702020302020201020201413012060A04007F000702020302020201020201453012060A04007F0007020204020202010202010D301C060904007F000702020302300C060704007F0007010202010D020141301C060904007F000702020302300C060704007F0007010202010D020145302A060804007F0007020206161E687474703A2F2F6273692E62756E642E64652F6369662F6E70612E786D6C</iso:EFCardAccess>
507
        <iso:IDPICC xmlns="urn:iso:std:iso-iec:24727:tech:schema">9CB2975C72066249DE0533093E70B62F82D40F7CC69A0F502AE7C33D0543540D</iso:IDPICC>
508
        <iso:Challenge xmlns="urn:iso:std:iso-iec:24727:tech:schema">DFDF4C39811377CC</iso:Challenge>
509
      </iso:AuthenticationProtocolData>
510
    </iso:DIDAuthenticateResponse>
511
  </Body>
512
</Envelope>
513

    
514
2014-11-22 12:58:27,263 [PAOS] WARN  org.openecard.transport.paos.PAOS:399 - The PAOS endpoint sent the http status code 200 which does not conform to thePAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
515
2014-11-22 12:58:27,265 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
516
  HTTP/1.1 200 OK
517
  Server: Apache-Coyote/1.1
518
  Content-Security-Policy: default-src 'self'
519
  Content-Type: application/vnd.paos+xml;charset=ISO-8859-1
520
  Transfer-Encoding: chunked
521
  Date: Sat, 22 Nov 2014 11:58:30 GMT
522
<?xml version="1.0" encoding="UTF-8"?><soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
523
   <soap:Header>
524
      <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8470" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
525
      <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a9210348-708c-4631-8930-60df978a2427</RelatesTo>
526
      <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:f732b084-9a3b-4c13-9dc1-4ddd463a9121</MessageID>
527
   </soap:Header>
528
   <soap:Body>
529
   <DIDAuthenticate xmlns="urn:iso:std:iso-iec:24727:tech:schema">
530
<ConnectionHandle xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="ConnectionHandle">
531
<ChannelHandle>
532
<SessionIdentifier>dS1Z02IQbRAPkgm0xvkpig</SessionIdentifier>
533
</ChannelHandle>
534
<ContextHandle>CE8CC1C8BB660931A6428F4833D5C668</ContextHandle>
535
<IFDName>REINER SCT cyberJack RFID basis 0</IFDName>
536
<SlotIndex>0</SlotIndex>
537
<CardApplication>3F00</CardApplication>
538
<SlotHandle>B84FBB0CE5616F3055F2FFB1A91DCACD</SlotHandle>
539
<RecognitionInfo>
540
<CardType>http://bsi.bund.de/cif/npa.xml</CardType>
541
</RecognitionInfo>
542
</ConnectionHandle>
543
<DIDName>PIN</DIDName>
544
<AuthenticationProtocolData Protocol="urn:oid:1.3.162.15480.3.0.14.2" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="EAC2InputType">
545
<Certificates xmlns="unknownNamespace">
546
<Certificate>7F2181E77F4E81A05F290100420E44454356434165494430303130337F494F060A04007F0007020202020386410492F4A8D05626617CDB6C901283067E46644B55E28A9C2ED1EF497035FF0ED77B6FD8EE0450A0FD66FEFECA0BF9B70D231627046276C3C3A6091B83A8EE2D1A185F2010444544566549444454523130313431357F4C12060904007F0007030102025305400513FF875F25060104010002035F24060105000102015F374069DDD51F6B1593BC47589F4985072CCA79A569808748882F497E5645A52E5C34912AF6079FA61CC673B747A8E7FFF59A7B9E23B5984EFE80253CC145823BA836</Certificate>
547
<Certificate>7F218201487F4E8201005F2901004210444544566549444454523130313431357F494F060A04007F0007020202020386410467E926AF24053149B949E1B1CE543BB2D4E48A5389CFEBADEAD37D68E4BED1705B85124F2B41EA0E6D4A47B3F3CC74A20586047A4E1096BD84230FB31FB065D55F200E44453030303031333630303434307F4C12060904007F00070301020253050001029B005F25060104010102025F2406010401010203655E732D060904007F0007030103028020B92C59DEE2AF301AC9095E7FE7FCE5441B2A52E204C6F12074F8B92D15A1124F732D060904007F00070301030180205567F538866593B65C2CB45285593AFFCF15B796A9B4315945FD71F16C7B62DE5F37404BA3060E647EFE4D7639D88C1925EB09834FC8B46C42F62414813318608DA08C772135C2B2385CAC5745262D4355768E951F1CA0128E70BE57D400F9BD0862A0</Certificate>
548
</Certificates>
549
<EphemeralPublicKey>144A1E28FDAB1E79131F8BCD9F4B4F5693802FC8A49533B8905912B420BFF0446D08A871E9A7435E9814F916C77352B8ED32F9D2D39BAB07082ACD42524F9FAB</EphemeralPublicKey>
550
<Signature>65A40722439E4D41D229C355CE40AA46151DFBCFBE93E7314995A1E7CCAA985F382B530BB4D31CB4487C1523504153B7028D0BFF7493FBDE8916344B34E86A2F</Signature>
551
</AuthenticationProtocolData>
552
</DIDAuthenticate>
553
</soap:Body>
554
</soap:Envelope>
555

    
556

    
557
2014-11-22 12:58:27,267 [PAOS] DEBUG org.openecard.transport.paos.PAOS:208 - Message received:
558
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
559
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
560
  <soap:Header>
561
    <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id8470" refToMessageID="ae1836b5-fcdf-4192-bd69-63983db84a3e"/>
562
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a9210348-708c-4631-8930-60df978a2427</RelatesTo>
563
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:f732b084-9a3b-4c13-9dc1-4ddd463a9121</MessageID>
564
  </soap:Header>
565
  <soap:Body>
566
    <DIDAuthenticate xmlns="urn:iso:std:iso-iec:24727:tech:schema">
567
      <ConnectionHandle xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:type="ConnectionHandle">
568
        <ChannelHandle>
569
          <SessionIdentifier>dS1Z02IQbRAPkgm0xvkpig</SessionIdentifier>
570
        </ChannelHandle>
571
        <ContextHandle>CE8CC1C8BB660931A6428F4833D5C668</ContextHandle>
572
        <IFDName>REINER SCT cyberJack RFID basis 0</IFDName>
573
        <SlotIndex>0</SlotIndex>
574
        <CardApplication>3F00</CardApplication>
575
        <SlotHandle>B84FBB0CE5616F3055F2FFB1A91DCACD</SlotHandle>
576
        <RecognitionInfo>
577
          <CardType>http://bsi.bund.de/cif/npa.xml</CardType>
578
        </RecognitionInfo>
579
      </ConnectionHandle>
580
      <DIDName>PIN</DIDName>
581
      <AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="EAC2InputType">
582
        <Certificates xmlns="unknownNamespace">
583
          <Certificate>7F2181E77F4E81A05F290100420E44454356434165494430303130337F494F060A04007F0007020202020386410492F4A8D05626617CDB6C901283067E46644B55E28A9C2ED1EF497035FF0ED77B6FD8EE0450A0FD66FEFECA0BF9B70D231627046276C3C3A6091B83A8EE2D1A185F2010444544566549444454523130313431357F4C12060904007F0007030102025305400513FF875F25060104010002035F24060105000102015F374069DDD51F6B1593BC47589F4985072CCA79A569808748882F497E5645A52E5C34912AF6079FA61CC673B747A8E7FFF59A7B9E23B5984EFE80253CC145823BA836</Certificate>
584
          <Certificate>7F218201487F4E8201005F2901004210444544566549444454523130313431357F494F060A04007F0007020202020386410467E926AF24053149B949E1B1CE543BB2D4E48A5389CFEBADEAD37D68E4BED1705B85124F2B41EA0E6D4A47B3F3CC74A20586047A4E1096BD84230FB31FB065D55F200E44453030303031333630303434307F4C12060904007F00070301020253050001029B005F25060104010102025F2406010401010203655E732D060904007F0007030103028020B92C59DEE2AF301AC9095E7FE7FCE5441B2A52E204C6F12074F8B92D15A1124F732D060904007F00070301030180205567F538866593B65C2CB45285593AFFCF15B796A9B4315945FD71F16C7B62DE5F37404BA3060E647EFE4D7639D88C1925EB09834FC8B46C42F62414813318608DA08C772135C2B2385CAC5745262D4355768E951F1CA0128E70BE57D400F9BD0862A0</Certificate>
585
        </Certificates>
586
        <EphemeralPublicKey>144A1E28FDAB1E79131F8BCD9F4B4F5693802FC8A49533B8905912B420BFF0446D08A871E9A7435E9814F916C77352B8ED32F9D2D39BAB07082ACD42524F9FAB</EphemeralPublicKey>
587
        <Signature>65A40722439E4D41D229C355CE40AA46151DFBCFBE93E7314995A1E7CCAA985F382B530BB4D31CB4487C1523504153B7028D0BFF7493FBDE8916344B34E86A2F</Signature>
588
      </AuthenticationProtocolData>
589
    </DIDAuthenticate>
590
  </soap:Body>
591
</soap:Envelope>
592

    
593
2014-11-22 12:58:27,272 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDAuthenticate
594
2014-11-22 12:58:27,275 [PAOS] WARN  o.o.c.c.a.c.CardVerifiableCertificateChain:60 - Verification of the certificate chain is disabled.
595
2014-11-22 12:58:27,279 [PAOS] ERROR o.o.s.p.e.TerminalAuthenticationStep:123 - null
596
java.lang.NullPointerException: null
597
	at org.openecard.sal.protocol.eac.AuthenticationHelper.performAuth(AuthenticationHelper.java:74) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
598
	at org.openecard.sal.protocol.eac.TerminalAuthenticationStep.perform(TerminalAuthenticationStep.java:107) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
599
	at org.openecard.sal.protocol.eac.TerminalAuthenticationStep.perform(TerminalAuthenticationStep.java:50) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
600
	at org.openecard.addon.sal.SALProtocolBaseImpl.perform(SALProtocolBaseImpl.java:163) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
601
	at org.openecard.addon.sal.SALProtocolBaseImpl.didAuthenticate(SALProtocolBaseImpl.java:244) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
602
	at org.openecard.addon.sal.SALProtocolProxy.didAuthenticate(SALProtocolProxy.java:147) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
603
	at org.openecard.sal.TinySAL.didAuthenticate(TinySAL.java:1938) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
604
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_25]
605
	at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
606
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[na:1.8.0_25]
607
	at java.lang.reflect.Method.invoke(Unknown Source) ~[na:1.8.0_25]
608
	at org.openecard.transport.dispatcher.Service.invoke(Service.java:192) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
609
	at org.openecard.transport.dispatcher.MessageDispatcher.deliver(MessageDispatcher.java:98) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
610
	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:332) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
611
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:97) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
612
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:48) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
613
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
614
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
615
2014-11-22 12:58:27,281 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request (before adding content):
616
  POST /ecardpaos/paosreceiver HTTP/1.1
617
  Connection: keep-alive
618
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
619
  Host: prodpaos.governikus-eid.de:443
620
  PAOS: ver="urn:liberty:paos:2006-08" ;"urn:iso:std:iso-iec:24727:tech:schema:Transmit';"http://www.bsi.bund.de/ecard/api/1.0#InitializeFramework';"urn:iso:std:iso-iec:24727:tech:schema:Hash';"urn:iso:std:iso-iec:24727:tech:schema:ACLList';"urn:iso:std:iso-iec:24727:tech:schema:DIDGet';"urn:iso:std:iso-iec:24727:tech:schema:DIDList';"urn:iso:std:iso-iec:24727:tech:schema:DSIList';"urn:iso:std:iso-iec:24727:tech:schema:DSIRead';"urn:iso:std:iso-iec:24727:tech:schema:DSIWrite';"urn:iso:std:iso-iec:24727:tech:schema:Encipher';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetList';"urn:iso:std:iso-iec:24727:tech:schema:DataSetSelect';"urn:iso:std:iso-iec:24727:tech:schema:DIDAuthenticate';"urn:iso:std:iso-iec:24727:tech:schema:GetRandom';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe';"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList';"urn:iso:std:iso-iec:24727:tech:schema:StartSession'
621
  Accept: text/xml, application/xml, application/vnd.paos+xml
622

    
623
2014-11-22 12:58:27,282 [PAOS] DEBUG org.openecard.transport.paos.PAOS:228 - Message sent:
624
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
625
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
626
  <Header>
627
    <PAOS xmlns:ns0="http://schemas.xmlsoap.org/soap/envelope/" ns0:actor="http://schemas.xmlsoap.org/soap/actor/next" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" ns1:mustUnderstand="1" xmlns="urn:liberty:paos:2006-08">
628
      <Version>urn:liberty:paos:2006-08</Version>
629
      <EndpointReference>
630
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
631
        <MetaData>
632
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
633
        </MetaData>
634
      </EndpointReference>
635
    </PAOS>
636
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
637
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
638
    </ReplyTo>
639
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:f732b084-9a3b-4c13-9dc1-4ddd463a9121</RelatesTo>
640
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:d97dd0f5-62f7-4004-9fac-50af84050939</MessageID>
641
  </Header>
642
  <Body>
643
    <iso:DIDAuthenticateResponse xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns11="urn:oasis:names:tc:SAML:1.0:assertion" xmlns:ns12="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns13="http://www.w3.org/2001/04/xmlenc#" xmlns:ns14="http://ws.openecard.org/schema" xmlns:ns15="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns16="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#" xmlns:ns5="http://uri.etsi.org/02231/v2.1.1#" xmlns:ns6="http://uri.etsi.org/02231/v2.x#" xmlns:ns7="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns8="http://www.setcce.org/schemas/ers" xmlns:ns9="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#">
644
      <ns3:Result>
645
        <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns3:ResultMajor>
646
        <ns3:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns3:ResultMinor>
647
      </ns3:Result>
648
    </iso:DIDAuthenticateResponse>
649
  </Body>
650
</Envelope>
651

    
652
2014-11-22 12:58:27,388 [PAOS] WARN  org.openecard.transport.paos.PAOS:399 - The PAOS endpoint sent the http status code 200 which does not conform to thePAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
653
2014-11-22 12:58:27,389 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
654
  HTTP/1.1 200 OK
655
  Server: Apache-Coyote/1.1
656
  Content-Security-Policy: default-src 'self'
657
  Content-Length: 0
658
  Date: Sat, 22 Nov 2014 11:58:30 GMT
659

    
660

    
661
2014-11-22 12:58:27,397 [PAOS] ERROR org.openecard.transport.paos.PAOS:219 - Vorzeitiges Dateiende.
662
org.xml.sax.SAXParseException: Vorzeitiges Dateiende.
663
	at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(Unknown Source) ~[na:1.8.0_25]
664
	at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(Unknown Source) ~[na:1.8.0_25]
665
	at javax.xml.parsers.DocumentBuilder.parse(Unknown Source) ~[na:1.8.0_25]
666
	at org.openecard.ws.jaxb.JAXBMarshaller.str2doc(JAXBMarshaller.java:152) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
667
	at org.openecard.transport.paos.PAOS.processPAOSRequest(PAOS.java:201) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
668
	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:322) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
669
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:97) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
670
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:48) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
671
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
672
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
673
2014-11-22 12:58:27,398 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationDisconnect
674
2014-11-22 12:58:27,400 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:96 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
675
2014-11-22 12:58:27,411 [Thread-5] ERROR o.o.binding.tctoken.TCTokenHandler:400 - org.openecard.transport.paos.PAOSException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Vorzeitiges Dateiende.
676
java.util.concurrent.ExecutionException: org.openecard.transport.paos.PAOSException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Vorzeitiges Dateiende.
677
	at java.util.concurrent.FutureTask.report(Unknown Source) ~[na:1.8.0_25]
678
	at java.util.concurrent.FutureTask.get(Unknown Source) ~[na:1.8.0_25]
679
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(TCTokenHandler.java:395) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
680
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(TCTokenHandler.java:205) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
681
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(TCTokenHandler.java:330) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
682
	at org.openecard.binding.tctoken.ActivationAction.execute(ActivationAction.java:88) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
683
	at org.openecard.addon.bind.AppPluginActionProxy.execute(AppPluginActionProxy.java:52) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
684
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(HttpAppPluginActionHandler.java:127) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
685
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java:423) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
686
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java:341) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
687
	at org.openecard.control.binding.http.HTTPService$1.run(HTTPService.java:124) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
688
Caused by: org.openecard.transport.paos.PAOSException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Vorzeitiges Dateiende.
689
	at org.openecard.transport.paos.PAOS.processPAOSRequest(PAOS.java:220) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
690
	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:322) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
691
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:97) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
692
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:48) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
693
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_25]
694
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_25]
695
Caused by: org.xml.sax.SAXParseException: Vorzeitiges Dateiende.
696
	at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(Unknown Source) ~[na:1.8.0_25]
697
	at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(Unknown Source) ~[na:1.8.0_25]
698
	at javax.xml.parsers.DocumentBuilder.parse(Unknown Source) ~[na:1.8.0_25]
699
	at org.openecard.ws.jaxb.JAXBMarshaller.str2doc(JAXBMarshaller.java:152) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
700
	at org.openecard.transport.paos.PAOS.processPAOSRequest(PAOS.java:201) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
701
	... 5 common frames omitted
702
2014-11-22 12:58:27,412 [Thread-5] ERROR o.o.binding.tctoken.TCTokenHandler:343 - org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Vorzeitiges Dateiende.
703
org.openecard.transport.paos.PAOSException: org.xml.sax.SAXParseException; lineNumber: 1; columnNumber: 1; Vorzeitiges Dateiende.
704
	at org.openecard.transport.paos.PAOS.processPAOSRequest(PAOS.java:220) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
705
	at org.openecard.transport.paos.PAOS.sendStartPAOS(PAOS.java:322) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
706
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:97) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
707
	at org.openecard.binding.tctoken.PAOSTask.call(PAOSTask.java:48) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
708
	at java.util.concurrent.FutureTask.run(Unknown Source) ~[na:1.8.0_25]
709
	at java.lang.Thread.run(Unknown Source) ~[na:1.8.0_25]
710
Caused by: org.xml.sax.SAXParseException: Vorzeitiges Dateiende.
711
	at com.sun.org.apache.xerces.internal.parsers.DOMParser.parse(Unknown Source) ~[na:1.8.0_25]
712
	at com.sun.org.apache.xerces.internal.jaxp.DocumentBuilderImpl.parse(Unknown Source) ~[na:1.8.0_25]
713
	at javax.xml.parsers.DocumentBuilder.parse(Unknown Source) ~[na:1.8.0_25]
714
	at org.openecard.ws.jaxb.JAXBMarshaller.str2doc(JAXBMarshaller.java:152) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
715
	at org.openecard.transport.paos.PAOS.processPAOSRequest(PAOS.java:201) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
716
	... 5 common frames omitted
717
2014-11-22 12:58:27,415 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://prod.governikus-eid.de/gov_autent/async?refID=6607f485f04120162ed46d482f7a72e35453d8ae
718
2014-11-22 12:58:27,416 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
719
2014-11-22 12:58:27,417 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
720
2014-11-22 12:58:27,668 [Thread-5] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
721
2014-11-22 12:58:27,669 [Thread-5] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
722
2014-11-22 12:58:27,670 [Thread-5] DEBUG o.o.common.util.TR03112Utils:54 - Checking SOP for https://prod.governikus-eid.de/gov_autent/async?refID=6607f485f04120162ed46d482f7a72e35453d8ae and https://tbk.ego-mv.de/BuergerKontoWeb.
723
2014-11-22 12:58:27,670 [Thread-5] ERROR o.o.common.util.TR03112Utils:65 - SOP violated; the hosts do not match.
724
2014-11-22 12:58:27,671 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
725
  GET /gov_autent/async?refID=6607f485f04120162ed46d482f7a72e35453d8ae HTTP/1.1
726
  Connection: keep-alive
727
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
728
  Host: prod.governikus-eid.de
729
  Accept: text/xml, */*;q=0.8
730
  Accept-Charset: utf-8, *;q=0.8
731

    
732
2014-11-22 12:58:27,736 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
733
  HTTP/1.1 302 Moved Temporarily
734
  Server: Apache-Coyote/1.1
735
  Content-Security-Policy: default-src 'self'
736
  Cache-Control: no-cache, no-store
737
  Pragma: no-cache
738
  Location: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14&SAMLResponse=pVfZkqPIFf0VRc0jVrFvFV09wQ6SEBJIQvAyAWSyiUWsEoQ%2F3lRX93jcDo8n7Ag9iEvmyXvPOZfM%2FPLrsyxWI2y7rK7eX%2FBX7GUFq6gGWZW8v5xP6pp7%2BfXrly4oC%2BL%2BZsPuXlcdXC2Tqu7tM%2Fr%2BMrTVWx10WfdWBSXs3vrozRHM3Rvxir3d27qvo7p4Wcmw67Mq6L8tlPb9vXtD0T68vcKkXpfjK4CoOMA2ge22rvrahSH6gWLDCGYjdGA7FrD%2FtYPdR6oGeCcYPMIAR60pJgRrCgJ6zUcssw7wkIQMxNkIp15Whvz%2BEvMUxbIRvwZMTK%2BpEETrICCjNQtpjIM8TwOMX0ZWP8o71e8vMCCWH86vWQJblgg5sA7CZQkGMIAARIhBml7mdN0Ajarrg6p%2FfyEwnFrj%2BJogTjj%2BRnNv5MInTvsvq8sPghdKXr7T%2BfZtcvtHLv%2BcyqDrYPtB38tXrV4kq7Lb0K2EoYdV%2FwX9JsZ3zN8Fc%2FqgH7qfHqUawNUlKAb45%2BstVS2Tv4sOYPsj8fvbJ%2BzPOGHdrcMWlrBatPx0QJlVdfsx7k0x5N8U27bsF%2FTr92T%2FiPNTiuaicpDAr0K1gm1bt6tH0K1aeK%2FbHoJV3NblChry%2BsMUsH1bfbhpMdPj8XgNu%2Bw1HCrwYSeYAXShbBmC4q8k2sJuKPpvKaFRXZZ19UtW9QuPQaF8LPJ3py5hny7OXz0WSlePtl7%2BZtWqT%2BEKSkELhIOxqj%2Bes271Cfy3VVL3q2ooiiW9z%2BZ4%2Fam8H7X8FP7hAqWK2um%2BlCX8kPd%2FcsSX59K2b7%2BDyUEffMf5ePHZcN8pepCvdZugBIbhKEahSy8vI35ZzAzeX37jA4qgOZ7lGUiGEU7iGAMwmiX4gGCYpYteVqfpvhjnv%2BIpxYcT%2BsU0f8xsca%2B5cFyDlVAkdZv1afkXsALY4QS3jsLowz2ge9vCyaji%2BnuFoPsPGBiK8R%2F1gS5LfvkpEwgWkM%2BaOYZmiIBjAcfzFAcpLORDFmcYngMcy0QfHfv%2F19B2wRr%2Fjf4o4BuYlN1T2H7o9C%2BBb3351bY1Id0rogWYMQCIENybs8XxJ%2BcsW5qcB061O1f6Dh6Iauuw%2FrTjuHlPVldp%2Bxipq%2Bz6c1IB3AugABqRZLwNr9Nn3I8l1DefO9WuaklK%2BbjrEDiOZ0qvisOY%2BjpfbPNThUhFz5549%2Bw3PQUpJK6PNcw26KVrfeVpNJ5zIZuMvOnzrQoudIbpo%2Fs8yCXKCNy56UjKVWy2BdfyJKd8JQrH0myBnYGn1s71jYKRGM63IpCb6CqkPcFi%2BK6s7eeQ98c9PbAE4ey322KgtCLxk546somvPYvHUX1cdrsylPCngNjEpKgRrsnSBjb%2BxAclcVRJYXc1%2BFRFL4faFWL4wJr9yB%2FmSLrODOEn7%2B9f0D9w%2F0n1v4Q%2B5fgc9HsvLT5ZYv903V8R0KucHh09JNasPggvlwFTOIO0BspOWMRMRMYxEzZLiXK%2FjbFbR%2BwRMWoQq4xIG9hzURiDr56PAAWi50kwP0GfpwuZUrlyTq%2B5LmoATW%2BpOyIMc9yEWJFXwn68EhDnmI65JL0Q57AWfdNDeW8YnPu8H592JGI9e5CrqqOCTencHlyhDIcou9TE8uliYtilaWxAMbIAJUy3Z3JWd07e05GaIcZMKEJO8swJbe7unBdAPUbPOCFdGvD7XnFL%2BVKJ3DTaiqSadn8Tr95hKEv04Q9ONKnROcU1w4cVUJyz3jCEABb%2FmrZjAPVaHCOav8f6jGUdlzV4lnlP5PB4oszpdhmft%2FmBjOVCllsAffaO%2FSxvr1m7ObEmr2SHDOMEmqZHxIEgvTZYOkwRXib4wbXQq97tHPeOpbEXAYzFo3i73Sl70dsBUzrHtqRrUFOss%2B5eMT1srGHqRVKytuVE7FGJvDsbmarPucejsyrpm4tyzA%2BIwISOwtwSd3cWacZg7XaxP6JZHFtP2%2Fu0mwUSEMPxaKNZKSJOXj8FrDk3%2BlbIOHA1FJnDVf%2BmicJB1%2Bazy6XRnoskZcYvtjHydbu5a8Y0pVWsOKPFx%2FWTPOJ7vjUAlHv%2BwTleIodW3SzKpgTTWmZ9PsYyHrcIi%2BeleRbFyVZNBbHSiswQMG%2BnG2Y4423u6RK9%2BZ1iI0lQNrr1MJInFXeFy2YmMUMY%2BLKF47kzpQ8zRM8wjgaKFdNYPwszlxRqHxW6fL%2FkBSql%2BpU3sAgJ9r7m05x48k9KDsvpMJ0Ef7%2BLNIezZn932NR3obihWz0jZq4h0osozJcsJ%2BSylhDyYJ4S2gGux25C6DqOt8NDOtxTNkogjKuJDH0iBN3XRL0MqlKSLluU9NgT4cinwnjygkDSXWKN8yHbO5cRK9y7ods3zJQnHmdGFLF4zTlaZ23eQM084r1qhOlTBh2hX%2Bq7ToSR3riBdhOrTTXzrKPmg5I8jjfLEFlE4F3lVOy947DZmNKMYGY8eA2pCMU8Y7qR8UlYBifzsHvsbwgeyHJlxm6eq%2FLRdzN2QJsrualyYWAf6EG4Xh7z7I2255Y3VLK7MYzt45z63G47VYnWHm1LgZrRExJhoaMRqRJbxPh1kiInQilfILQkRXKSkfJzhsCQHJpY1vVHNcZEvyUKDtnccTm1XJW2%2B1jVvJBjB5OIsOtQITp5cTU1FEvgeOlk0fkmoXCdaw5XvtYl6wTAczfQp8Nj2%2BXciVKpKowYvASZJo2DqYM80dVN34jhNR2O7NxkC6gtbEIZVZ562xJbbHfk0yEQ936jgeSQQurM9poHEh3c5%2BgxR1MBKc99dPgupCvS7IdR6v1uXjYdfTlbCGR7yy7GDjmYCEROWz9F58aUjqfdpcuBG%2Bf8rC73gVIQdGCdEmqKQKnuCJDU04lx%2BAhvvCNuSTwtEPHtykxn4qBoo7GwXYo3E2u5a5BYm8xaPpmTcGmEFKm3j%2FAestFxOd6cC9wd8kson9yKYwg1969hKkWdPGxjSF9Nn6ErZ9l6vcdcV5q9OQenmj2Q415LT01txsAgZKyMiQuWT72y2boHuZnO2mW6CYC4yNfhgCrYxFYj%2B9xbwi1voytTkNpzF8B4r9x4MGQEaTyu8pDkm3BLR3QdmIF1Y8yS2wY3j6KJ%2B%2FO25ZuDvFWUUFg27vPV7Lx7k0knLaeJ1Hi4vi%2FmzyM3bHyk6VvCC%2FVsM9fK4G40X1RxZIvJPeW22%2FGkWlQuhu1wpGKVUx%2F2Y5Z8z8DQIMemwZouV9L32pOidbtt7Q6QdNUgcHUk9JH%2BgDaRaFAJsEKxNYLqvvOSbXLPajvlB39%2B4MSkgfmhziFZh%2F%2FTRvt99%2F28yfz7ufif94YfF7Wv%2FwA%3D&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2000%2F09%2Fxmldsig%23rsa-sha1&Signature=KI9CFYTeywUuD75uKiwaDrs1hFcET611EZfk9FKvEWGxLhgyHkZYwHwoDivCqjiIEwcR%2BrSVahvy%0At9EuoHOgwazbB1OI7OMNMTclPmKCJJf6pqnqwy6xcOPn7kimjJF5C35u0rTWGDOkQXA3whATBAAf%0APJ1UuJ7yGyRdxk32b5nKY27pFp5S%2BH70n2zofuvsAXYVnSzY64qmeSOmK%2FtWHc1eZLTbXv8t3V9D%0AD4uWip5I7s4dUky%2FdnhIxAPzRdMu2Ow65nYZ2d2Pmb1sTCul7CBGoPDRVS%2FyVJMdL%2BOtGbB1vlix%0AnMvx4rYoN1vjiR2NwYAxHWbU9UvZqBXeew%2FzvA%3D%3D
739
  Content-Length: 0
740
  Date: Sat, 22 Nov 2014 11:58:30 GMT
741

    
742

    
743
2014-11-22 12:58:27,736 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14&SAMLResponse=pVfZkqPIFf0VRc0jVrFvFV09wQ6SEBJIQvAyAWSyiUWsEoQ%2F3lRX93jcDo8n7Ag9iEvmyXvPOZfM%2FPLrsyxWI2y7rK7eX%2FBX7GUFq6gGWZW8v5xP6pp7%2BfXrly4oC%2BL%2BZsPuXlcdXC2Tqu7tM%2Fr%2BMrTVWx10WfdWBSXs3vrozRHM3Rvxir3d27qvo7p4Wcmw67Mq6L8tlPb9vXtD0T68vcKkXpfjK4CoOMA2ge22rvrahSH6gWLDCGYjdGA7FrD%2FtYPdR6oGeCcYPMIAR60pJgRrCgJ6zUcssw7wkIQMxNkIp15Whvz%2BEvMUxbIRvwZMTK%2BpEETrICCjNQtpjIM8TwOMX0ZWP8o71e8vMCCWH86vWQJblgg5sA7CZQkGMIAARIhBml7mdN0Ajarrg6p%2FfyEwnFrj%2BJogTjj%2BRnNv5MInTvsvq8sPghdKXr7T%2BfZtcvtHLv%2BcyqDrYPtB38tXrV4kq7Lb0K2EoYdV%2FwX9JsZ3zN8Fc%2FqgH7qfHqUawNUlKAb45%2BstVS2Tv4sOYPsj8fvbJ%2BzPOGHdrcMWlrBatPx0QJlVdfsx7k0x5N8U27bsF%2FTr92T%2FiPNTiuaicpDAr0K1gm1bt6tH0K1aeK%2FbHoJV3NblChry%2BsMUsH1bfbhpMdPj8XgNu%2Bw1HCrwYSeYAXShbBmC4q8k2sJuKPpvKaFRXZZ19UtW9QuPQaF8LPJ3py5hny7OXz0WSlePtl7%2BZtWqT%2BEKSkELhIOxqj%2Bes271Cfy3VVL3q2ooiiW9z%2BZ4%2Fam8H7X8FP7hAqWK2um%2BlCX8kPd%2FcsSX59K2b7%2BDyUEffMf5ePHZcN8pepCvdZugBIbhKEahSy8vI35ZzAzeX37jA4qgOZ7lGUiGEU7iGAMwmiX4gGCYpYteVqfpvhjnv%2BIpxYcT%2BsU0f8xsca%2B5cFyDlVAkdZv1afkXsALY4QS3jsLowz2ge9vCyaji%2BnuFoPsPGBiK8R%2F1gS5LfvkpEwgWkM%2BaOYZmiIBjAcfzFAcpLORDFmcYngMcy0QfHfv%2F19B2wRr%2Fjf4o4BuYlN1T2H7o9C%2BBb3351bY1Id0rogWYMQCIENybs8XxJ%2BcsW5qcB061O1f6Dh6Iauuw%2FrTjuHlPVldp%2Bxipq%2Bz6c1IB3AugABqRZLwNr9Nn3I8l1DefO9WuaklK%2BbjrEDiOZ0qvisOY%2BjpfbPNThUhFz5549%2Bw3PQUpJK6PNcw26KVrfeVpNJ5zIZuMvOnzrQoudIbpo%2Fs8yCXKCNy56UjKVWy2BdfyJKd8JQrH0myBnYGn1s71jYKRGM63IpCb6CqkPcFi%2BK6s7eeQ98c9PbAE4ey322KgtCLxk546somvPYvHUX1cdrsylPCngNjEpKgRrsnSBjb%2BxAclcVRJYXc1%2BFRFL4faFWL4wJr9yB%2FmSLrODOEn7%2B9f0D9w%2F0n1v4Q%2B5fgc9HsvLT5ZYv903V8R0KucHh09JNasPggvlwFTOIO0BspOWMRMRMYxEzZLiXK%2FjbFbR%2BwRMWoQq4xIG9hzURiDr56PAAWi50kwP0GfpwuZUrlyTq%2B5LmoATW%2BpOyIMc9yEWJFXwn68EhDnmI65JL0Q57AWfdNDeW8YnPu8H592JGI9e5CrqqOCTencHlyhDIcou9TE8uliYtilaWxAMbIAJUy3Z3JWd07e05GaIcZMKEJO8swJbe7unBdAPUbPOCFdGvD7XnFL%2BVKJ3DTaiqSadn8Tr95hKEv04Q9ONKnROcU1w4cVUJyz3jCEABb%2FmrZjAPVaHCOav8f6jGUdlzV4lnlP5PB4oszpdhmft%2FmBjOVCllsAffaO%2FSxvr1m7ObEmr2SHDOMEmqZHxIEgvTZYOkwRXib4wbXQq97tHPeOpbEXAYzFo3i73Sl70dsBUzrHtqRrUFOss%2B5eMT1srGHqRVKytuVE7FGJvDsbmarPucejsyrpm4tyzA%2BIwISOwtwSd3cWacZg7XaxP6JZHFtP2%2Fu0mwUSEMPxaKNZKSJOXj8FrDk3%2BlbIOHA1FJnDVf%2BmicJB1%2Bazy6XRnoskZcYvtjHydbu5a8Y0pVWsOKPFx%2FWTPOJ7vjUAlHv%2BwTleIodW3SzKpgTTWmZ9PsYyHrcIi%2BeleRbFyVZNBbHSiswQMG%2BnG2Y4423u6RK9%2BZ1iI0lQNrr1MJInFXeFy2YmMUMY%2BLKF47kzpQ8zRM8wjgaKFdNYPwszlxRqHxW6fL%2FkBSql%2BpU3sAgJ9r7m05x48k9KDsvpMJ0Ef7%2BLNIezZn932NR3obihWz0jZq4h0osozJcsJ%2BSylhDyYJ4S2gGux25C6DqOt8NDOtxTNkogjKuJDH0iBN3XRL0MqlKSLluU9NgT4cinwnjygkDSXWKN8yHbO5cRK9y7ods3zJQnHmdGFLF4zTlaZ23eQM084r1qhOlTBh2hX%2Bq7ToSR3riBdhOrTTXzrKPmg5I8jjfLEFlE4F3lVOy947DZmNKMYGY8eA2pCMU8Y7qR8UlYBifzsHvsbwgeyHJlxm6eq%2FLRdzN2QJsrualyYWAf6EG4Xh7z7I2255Y3VLK7MYzt45z63G47VYnWHm1LgZrRExJhoaMRqRJbxPh1kiInQilfILQkRXKSkfJzhsCQHJpY1vVHNcZEvyUKDtnccTm1XJW2%2B1jVvJBjB5OIsOtQITp5cTU1FEvgeOlk0fkmoXCdaw5XvtYl6wTAczfQp8Nj2%2BXciVKpKowYvASZJo2DqYM80dVN34jhNR2O7NxkC6gtbEIZVZ562xJbbHfk0yEQ936jgeSQQurM9poHEh3c5%2BgxR1MBKc99dPgupCvS7IdR6v1uXjYdfTlbCGR7yy7GDjmYCEROWz9F58aUjqfdpcuBG%2Bf8rC73gVIQdGCdEmqKQKnuCJDU04lx%2BAhvvCNuSTwtEPHtykxn4qBoo7GwXYo3E2u5a5BYm8xaPpmTcGmEFKm3j%2FAestFxOd6cC9wd8kson9yKYwg1969hKkWdPGxjSF9Nn6ErZ9l6vcdcV5q9OQenmj2Q415LT01txsAgZKyMiQuWT72y2boHuZnO2mW6CYC4yNfhgCrYxFYj%2B9xbwi1voytTkNpzF8B4r9x4MGQEaTyu8pDkm3BLR3QdmIF1Y8yS2wY3j6KJ%2B%2FO25ZuDvFWUUFg27vPV7Lx7k0knLaeJ1Hi4vi%2FmzyM3bHyk6VvCC%2FVsM9fK4G40X1RxZIvJPeW22%2FGkWlQuhu1wpGKVUx%2F2Y5Z8z8DQIMemwZouV9L32pOidbtt7Q6QdNUgcHUk9JH%2BgDaRaFAJsEKxNYLqvvOSbXLPajvlB39%2B4MSkgfmhziFZh%2F%2FTRvt99%2F28yfz7ufif94YfF7Wv%2FwA%3D&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2000%2F09%2Fxmldsig%23rsa-sha1&Signature=KI9CFYTeywUuD75uKiwaDrs1hFcET611EZfk9FKvEWGxLhgyHkZYwHwoDivCqjiIEwcR%2BrSVahvy%0At9EuoHOgwazbB1OI7OMNMTclPmKCJJf6pqnqwy6xcOPn7kimjJF5C35u0rTWGDOkQXA3whATBAAf%0APJ1UuJ7yGyRdxk32b5nKY27pFp5S%2BH70n2zofuvsAXYVnSzY64qmeSOmK%2FtWHc1eZLTbXv8t3V9D%0AD4uWip5I7s4dUky%2FdnhIxAPzRdMu2Ow65nYZ2d2Pmb1sTCul7CBGoPDRVS%2FyVJMdL%2BOtGbB1vlix%0AnMvx4rYoN1vjiR2NwYAxHWbU9UvZqBXeew%2FzvA%3D%3D
744
2014-11-22 12:58:27,737 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
745
2014-11-22 12:58:27,737 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
746
2014-11-22 12:58:27,957 [Thread-5] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
747
2014-11-22 12:58:27,958 [Thread-5] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
748
2014-11-22 12:58:27,959 [Thread-5] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
749
2014-11-22 12:58:27,959 [Thread-5] DEBUG o.o.common.util.TR03112Utils:54 - Checking SOP for https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14&SAMLResponse=pVfZkqPIFf0VRc0jVrFvFV09wQ6SEBJIQvAyAWSyiUWsEoQ%2F3lRX93jcDo8n7Ag9iEvmyXvPOZfM%2FPLrsyxWI2y7rK7eX%2FBX7GUFq6gGWZW8v5xP6pp7%2BfXrly4oC%2BL%2BZsPuXlcdXC2Tqu7tM%2Fr%2BMrTVWx10WfdWBSXs3vrozRHM3Rvxir3d27qvo7p4Wcmw67Mq6L8tlPb9vXtD0T68vcKkXpfjK4CoOMA2ge22rvrahSH6gWLDCGYjdGA7FrD%2FtYPdR6oGeCcYPMIAR60pJgRrCgJ6zUcssw7wkIQMxNkIp15Whvz%2BEvMUxbIRvwZMTK%2BpEETrICCjNQtpjIM8TwOMX0ZWP8o71e8vMCCWH86vWQJblgg5sA7CZQkGMIAARIhBml7mdN0Ajarrg6p%2FfyEwnFrj%2BJogTjj%2BRnNv5MInTvsvq8sPghdKXr7T%2BfZtcvtHLv%2BcyqDrYPtB38tXrV4kq7Lb0K2EoYdV%2FwX9JsZ3zN8Fc%2FqgH7qfHqUawNUlKAb45%2BstVS2Tv4sOYPsj8fvbJ%2BzPOGHdrcMWlrBatPx0QJlVdfsx7k0x5N8U27bsF%2FTr92T%2FiPNTiuaicpDAr0K1gm1bt6tH0K1aeK%2FbHoJV3NblChry%2BsMUsH1bfbhpMdPj8XgNu%2Bw1HCrwYSeYAXShbBmC4q8k2sJuKPpvKaFRXZZ19UtW9QuPQaF8LPJ3py5hny7OXz0WSlePtl7%2BZtWqT%2BEKSkELhIOxqj%2Bes271Cfy3VVL3q2ooiiW9z%2BZ4%2Fam8H7X8FP7hAqWK2um%2BlCX8kPd%2FcsSX59K2b7%2BDyUEffMf5ePHZcN8pepCvdZugBIbhKEahSy8vI35ZzAzeX37jA4qgOZ7lGUiGEU7iGAMwmiX4gGCYpYteVqfpvhjnv%2BIpxYcT%2BsU0f8xsca%2B5cFyDlVAkdZv1afkXsALY4QS3jsLowz2ge9vCyaji%2BnuFoPsPGBiK8R%2F1gS5LfvkpEwgWkM%2BaOYZmiIBjAcfzFAcpLORDFmcYngMcy0QfHfv%2F19B2wRr%2Fjf4o4BuYlN1T2H7o9C%2BBb3351bY1Id0rogWYMQCIENybs8XxJ%2BcsW5qcB061O1f6Dh6Iauuw%2FrTjuHlPVldp%2Bxipq%2Bz6c1IB3AugABqRZLwNr9Nn3I8l1DefO9WuaklK%2BbjrEDiOZ0qvisOY%2BjpfbPNThUhFz5549%2Bw3PQUpJK6PNcw26KVrfeVpNJ5zIZuMvOnzrQoudIbpo%2Fs8yCXKCNy56UjKVWy2BdfyJKd8JQrH0myBnYGn1s71jYKRGM63IpCb6CqkPcFi%2BK6s7eeQ98c9PbAE4ey322KgtCLxk546somvPYvHUX1cdrsylPCngNjEpKgRrsnSBjb%2BxAclcVRJYXc1%2BFRFL4faFWL4wJr9yB%2FmSLrODOEn7%2B9f0D9w%2F0n1v4Q%2B5fgc9HsvLT5ZYv903V8R0KucHh09JNasPggvlwFTOIO0BspOWMRMRMYxEzZLiXK%2FjbFbR%2BwRMWoQq4xIG9hzURiDr56PAAWi50kwP0GfpwuZUrlyTq%2B5LmoATW%2BpOyIMc9yEWJFXwn68EhDnmI65JL0Q57AWfdNDeW8YnPu8H592JGI9e5CrqqOCTencHlyhDIcou9TE8uliYtilaWxAMbIAJUy3Z3JWd07e05GaIcZMKEJO8swJbe7unBdAPUbPOCFdGvD7XnFL%2BVKJ3DTaiqSadn8Tr95hKEv04Q9ONKnROcU1w4cVUJyz3jCEABb%2FmrZjAPVaHCOav8f6jGUdlzV4lnlP5PB4oszpdhmft%2FmBjOVCllsAffaO%2FSxvr1m7ObEmr2SHDOMEmqZHxIEgvTZYOkwRXib4wbXQq97tHPeOpbEXAYzFo3i73Sl70dsBUzrHtqRrUFOss%2B5eMT1srGHqRVKytuVE7FGJvDsbmarPucejsyrpm4tyzA%2BIwISOwtwSd3cWacZg7XaxP6JZHFtP2%2Fu0mwUSEMPxaKNZKSJOXj8FrDk3%2BlbIOHA1FJnDVf%2BmicJB1%2Bazy6XRnoskZcYvtjHydbu5a8Y0pVWsOKPFx%2FWTPOJ7vjUAlHv%2BwTleIodW3SzKpgTTWmZ9PsYyHrcIi%2BeleRbFyVZNBbHSiswQMG%2BnG2Y4423u6RK9%2BZ1iI0lQNrr1MJInFXeFy2YmMUMY%2BLKF47kzpQ8zRM8wjgaKFdNYPwszlxRqHxW6fL%2FkBSql%2BpU3sAgJ9r7m05x48k9KDsvpMJ0Ef7%2BLNIezZn932NR3obihWz0jZq4h0osozJcsJ%2BSylhDyYJ4S2gGux25C6DqOt8NDOtxTNkogjKuJDH0iBN3XRL0MqlKSLluU9NgT4cinwnjygkDSXWKN8yHbO5cRK9y7ods3zJQnHmdGFLF4zTlaZ23eQM084r1qhOlTBh2hX%2Bq7ToSR3riBdhOrTTXzrKPmg5I8jjfLEFlE4F3lVOy947DZmNKMYGY8eA2pCMU8Y7qR8UlYBifzsHvsbwgeyHJlxm6eq%2FLRdzN2QJsrualyYWAf6EG4Xh7z7I2255Y3VLK7MYzt45z63G47VYnWHm1LgZrRExJhoaMRqRJbxPh1kiInQilfILQkRXKSkfJzhsCQHJpY1vVHNcZEvyUKDtnccTm1XJW2%2B1jVvJBjB5OIsOtQITp5cTU1FEvgeOlk0fkmoXCdaw5XvtYl6wTAczfQp8Nj2%2BXciVKpKowYvASZJo2DqYM80dVN34jhNR2O7NxkC6gtbEIZVZ562xJbbHfk0yEQ936jgeSQQurM9poHEh3c5%2BgxR1MBKc99dPgupCvS7IdR6v1uXjYdfTlbCGR7yy7GDjmYCEROWz9F58aUjqfdpcuBG%2Bf8rC73gVIQdGCdEmqKQKnuCJDU04lx%2BAhvvCNuSTwtEPHtykxn4qBoo7GwXYo3E2u5a5BYm8xaPpmTcGmEFKm3j%2FAestFxOd6cC9wd8kson9yKYwg1969hKkWdPGxjSF9Nn6ErZ9l6vcdcV5q9OQenmj2Q415LT01txsAgZKyMiQuWT72y2boHuZnO2mW6CYC4yNfhgCrYxFYj%2B9xbwi1voytTkNpzF8B4r9x4MGQEaTyu8pDkm3BLR3QdmIF1Y8yS2wY3j6KJ%2B%2FO25ZuDvFWUUFg27vPV7Lx7k0knLaeJ1Hi4vi%2FmzyM3bHyk6VvCC%2FVsM9fK4G40X1RxZIvJPeW22%2FGkWlQuhu1wpGKVUx%2F2Y5Z8z8DQIMemwZouV9L32pOidbtt7Q6QdNUgcHUk9JH%2BgDaRaFAJsEKxNYLqvvOSbXLPajvlB39%2B4MSkgfmhziFZh%2F%2FTRvt99%2F28yfz7ufif94YfF7Wv%2FwA%3D&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2000%2F09%2Fxmldsig%23rsa-sha1&Signature=KI9CFYTeywUuD75uKiwaDrs1hFcET611EZfk9FKvEWGxLhgyHkZYwHwoDivCqjiIEwcR%2BrSVahvy%0At9EuoHOgwazbB1OI7OMNMTclPmKCJJf6pqnqwy6xcOPn7kimjJF5C35u0rTWGDOkQXA3whATBAAf%0APJ1UuJ7yGyRdxk32b5nKY27pFp5S%2BH70n2zofuvsAXYVnSzY64qmeSOmK%2FtWHc1eZLTbXv8t3V9D%0AD4uWip5I7s4dUky%2FdnhIxAPzRdMu2Ow65nYZ2d2Pmb1sTCul7CBGoPDRVS%2FyVJMdL%2BOtGbB1vlix%0AnMvx4rYoN1vjiR2NwYAxHWbU9UvZqBXeew%2FzvA%3D%3D and https://tbk.ego-mv.de/BuergerKontoWeb.
750
2014-11-22 12:58:27,960 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:81 - HTTP Request:
751
  GET /BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14&SAMLResponse=pVfZkqPIFf0VRc0jVrFvFV09wQ6SEBJIQvAyAWSyiUWsEoQ%2F3lRX93jcDo8n7Ag9iEvmyXvPOZfM%2FPLrsyxWI2y7rK7eX%2FBX7GUFq6gGWZW8v5xP6pp7%2BfXrly4oC%2BL%2BZsPuXlcdXC2Tqu7tM%2Fr%2BMrTVWx10WfdWBSXs3vrozRHM3Rvxir3d27qvo7p4Wcmw67Mq6L8tlPb9vXtD0T68vcKkXpfjK4CoOMA2ge22rvrahSH6gWLDCGYjdGA7FrD%2FtYPdR6oGeCcYPMIAR60pJgRrCgJ6zUcssw7wkIQMxNkIp15Whvz%2BEvMUxbIRvwZMTK%2BpEETrICCjNQtpjIM8TwOMX0ZWP8o71e8vMCCWH86vWQJblgg5sA7CZQkGMIAARIhBml7mdN0Ajarrg6p%2FfyEwnFrj%2BJogTjj%2BRnNv5MInTvsvq8sPghdKXr7T%2BfZtcvtHLv%2BcyqDrYPtB38tXrV4kq7Lb0K2EoYdV%2FwX9JsZ3zN8Fc%2FqgH7qfHqUawNUlKAb45%2BstVS2Tv4sOYPsj8fvbJ%2BzPOGHdrcMWlrBatPx0QJlVdfsx7k0x5N8U27bsF%2FTr92T%2FiPNTiuaicpDAr0K1gm1bt6tH0K1aeK%2FbHoJV3NblChry%2BsMUsH1bfbhpMdPj8XgNu%2Bw1HCrwYSeYAXShbBmC4q8k2sJuKPpvKaFRXZZ19UtW9QuPQaF8LPJ3py5hny7OXz0WSlePtl7%2BZtWqT%2BEKSkELhIOxqj%2Bes271Cfy3VVL3q2ooiiW9z%2BZ4%2Fam8H7X8FP7hAqWK2um%2BlCX8kPd%2FcsSX59K2b7%2BDyUEffMf5ePHZcN8pepCvdZugBIbhKEahSy8vI35ZzAzeX37jA4qgOZ7lGUiGEU7iGAMwmiX4gGCYpYteVqfpvhjnv%2BIpxYcT%2BsU0f8xsca%2B5cFyDlVAkdZv1afkXsALY4QS3jsLowz2ge9vCyaji%2BnuFoPsPGBiK8R%2F1gS5LfvkpEwgWkM%2BaOYZmiIBjAcfzFAcpLORDFmcYngMcy0QfHfv%2F19B2wRr%2Fjf4o4BuYlN1T2H7o9C%2BBb3351bY1Id0rogWYMQCIENybs8XxJ%2BcsW5qcB061O1f6Dh6Iauuw%2FrTjuHlPVldp%2Bxipq%2Bz6c1IB3AugABqRZLwNr9Nn3I8l1DefO9WuaklK%2BbjrEDiOZ0qvisOY%2BjpfbPNThUhFz5549%2Bw3PQUpJK6PNcw26KVrfeVpNJ5zIZuMvOnzrQoudIbpo%2Fs8yCXKCNy56UjKVWy2BdfyJKd8JQrH0myBnYGn1s71jYKRGM63IpCb6CqkPcFi%2BK6s7eeQ98c9PbAE4ey322KgtCLxk546somvPYvHUX1cdrsylPCngNjEpKgRrsnSBjb%2BxAclcVRJYXc1%2BFRFL4faFWL4wJr9yB%2FmSLrODOEn7%2B9f0D9w%2F0n1v4Q%2B5fgc9HsvLT5ZYv903V8R0KucHh09JNasPggvlwFTOIO0BspOWMRMRMYxEzZLiXK%2FjbFbR%2BwRMWoQq4xIG9hzURiDr56PAAWi50kwP0GfpwuZUrlyTq%2B5LmoATW%2BpOyIMc9yEWJFXwn68EhDnmI65JL0Q57AWfdNDeW8YnPu8H592JGI9e5CrqqOCTencHlyhDIcou9TE8uliYtilaWxAMbIAJUy3Z3JWd07e05GaIcZMKEJO8swJbe7unBdAPUbPOCFdGvD7XnFL%2BVKJ3DTaiqSadn8Tr95hKEv04Q9ONKnROcU1w4cVUJyz3jCEABb%2FmrZjAPVaHCOav8f6jGUdlzV4lnlP5PB4oszpdhmft%2FmBjOVCllsAffaO%2FSxvr1m7ObEmr2SHDOMEmqZHxIEgvTZYOkwRXib4wbXQq97tHPeOpbEXAYzFo3i73Sl70dsBUzrHtqRrUFOss%2B5eMT1srGHqRVKytuVE7FGJvDsbmarPucejsyrpm4tyzA%2BIwISOwtwSd3cWacZg7XaxP6JZHFtP2%2Fu0mwUSEMPxaKNZKSJOXj8FrDk3%2BlbIOHA1FJnDVf%2BmicJB1%2Bazy6XRnoskZcYvtjHydbu5a8Y0pVWsOKPFx%2FWTPOJ7vjUAlHv%2BwTleIodW3SzKpgTTWmZ9PsYyHrcIi%2BeleRbFyVZNBbHSiswQMG%2BnG2Y4423u6RK9%2BZ1iI0lQNrr1MJInFXeFy2YmMUMY%2BLKF47kzpQ8zRM8wjgaKFdNYPwszlxRqHxW6fL%2FkBSql%2BpU3sAgJ9r7m05x48k9KDsvpMJ0Ef7%2BLNIezZn932NR3obihWz0jZq4h0osozJcsJ%2BSylhDyYJ4S2gGux25C6DqOt8NDOtxTNkogjKuJDH0iBN3XRL0MqlKSLluU9NgT4cinwnjygkDSXWKN8yHbO5cRK9y7ods3zJQnHmdGFLF4zTlaZ23eQM084r1qhOlTBh2hX%2Bq7ToSR3riBdhOrTTXzrKPmg5I8jjfLEFlE4F3lVOy947DZmNKMYGY8eA2pCMU8Y7qR8UlYBifzsHvsbwgeyHJlxm6eq%2FLRdzN2QJsrualyYWAf6EG4Xh7z7I2255Y3VLK7MYzt45z63G47VYnWHm1LgZrRExJhoaMRqRJbxPh1kiInQilfILQkRXKSkfJzhsCQHJpY1vVHNcZEvyUKDtnccTm1XJW2%2B1jVvJBjB5OIsOtQITp5cTU1FEvgeOlk0fkmoXCdaw5XvtYl6wTAczfQp8Nj2%2BXciVKpKowYvASZJo2DqYM80dVN34jhNR2O7NxkC6gtbEIZVZ562xJbbHfk0yEQ936jgeSQQurM9poHEh3c5%2BgxR1MBKc99dPgupCvS7IdR6v1uXjYdfTlbCGR7yy7GDjmYCEROWz9F58aUjqfdpcuBG%2Bf8rC73gVIQdGCdEmqKQKnuCJDU04lx%2BAhvvCNuSTwtEPHtykxn4qBoo7GwXYo3E2u5a5BYm8xaPpmTcGmEFKm3j%2FAestFxOd6cC9wd8kson9yKYwg1969hKkWdPGxjSF9Nn6ErZ9l6vcdcV5q9OQenmj2Q415LT01txsAgZKyMiQuWT72y2boHuZnO2mW6CYC4yNfhgCrYxFYj%2B9xbwi1voytTkNpzF8B4r9x4MGQEaTyu8pDkm3BLR3QdmIF1Y8yS2wY3j6KJ%2B%2FO25ZuDvFWUUFg27vPV7Lx7k0knLaeJ1Hi4vi%2FmzyM3bHyk6VvCC%2FVsM9fK4G40X1RxZIvJPeW22%2FGkWlQuhu1wpGKVUx%2F2Y5Z8z8DQIMemwZouV9L32pOidbtt7Q6QdNUgcHUk9JH%2BgDaRaFAJsEKxNYLqvvOSbXLPajvlB39%2B4MSkgfmhziFZh%2F%2FTRvt99%2F28yfz7ufif94YfF7Wv%2FwA%3D&RelayState=1416657500765&SigAlg=http%3A%2F%2Fwww.w3.org%2F2000%2F09%2Fxmldsig%23rsa-sha1&Signature=KI9CFYTeywUuD75uKiwaDrs1hFcET611EZfk9FKvEWGxLhgyHkZYwHwoDivCqjiIEwcR%2BrSVahvy%0At9EuoHOgwazbB1OI7OMNMTclPmKCJJf6pqnqwy6xcOPn7kimjJF5C35u0rTWGDOkQXA3whATBAAf%0APJ1UuJ7yGyRdxk32b5nKY27pFp5S%2BH70n2zofuvsAXYVnSzY64qmeSOmK%2FtWHc1eZLTbXv8t3V9D%0AD4uWip5I7s4dUky%2FdnhIxAPzRdMu2Ow65nYZ2d2Pmb1sTCul7CBGoPDRVS%2FyVJMdL%2BOtGbB1vlix%0AnMvx4rYoN1vjiR2NwYAxHWbU9UvZqBXeew%2FzvA%3D%3D HTTP/1.1
752
  Connection: keep-alive
753
  User-Agent: Open-eCard-App/1.1.0-SNAPSHOT
754
  Host: tbk.ego-mv.de
755
  Accept: text/xml, */*;q=0.8
756
  Accept-Charset: utf-8, *;q=0.8
757

    
758
2014-11-22 12:58:28,113 [Thread-5] DEBUG o.o.transport.httpcore.HttpUtils:112 - HTTP Response:
759
  HTTP/1.1 303 See Other
760
  Server: Apache-Coyote/1.1
761
  Location: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14
762
  Cache-Control: no-cache, no-store
763
  Pragma: no-cache
764
  Content-Length: 0
765
  Date: Sat, 22 Nov 2014 11:58:40 GMT
766

    
767

    
768
2014-11-22 12:58:28,113 [Thread-5] INFO  o.o.binding.tctoken.ResourceContext:181 - Trying to load resource from: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14
769
2014-11-22 12:58:28,114 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
770
2014-11-22 12:58:28,114 [Thread-5] DEBUG o.o.crypto.tls.proxy.ProxySettings:158 - Selecting proxy: DIRECT
771
2014-11-22 12:58:28,338 [Thread-5] DEBUG o.o.common.util.TR03112Utils:104 - Hash of the retrieved server certificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
772
2014-11-22 12:58:28,339 [Thread-5] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 29F04459C39E4B68C70AA3961FA7B1B40E8A49238446CFE606C56FF8DC0989F4
773
2014-11-22 12:58:28,339 [Thread-5] DEBUG o.o.common.util.TR03112Utils:109 - CommCertificate: 561DE93EC27614978099C776CF1BE6A344851E658D36A04CE1577FEA42965EB3
774
2014-11-22 12:58:28,340 [Thread-5] DEBUG o.o.common.util.TR03112Utils:54 - Checking SOP for https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14 and https://tbk.ego-mv.de/BuergerKontoWeb.
775
2014-11-22 12:58:28,340 [Thread-5] DEBUG o.o.binding.tctoken.TCTokenHandler:455 - Setting redirect address to 'https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14'.
776
2014-11-22 12:58:28,341 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:183 - Recieved BindingResult with ResultCode REDIRECT
777
2014-11-22 12:58:28,346 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:165 - BindingResult contains NO body.
778
2014-11-22 12:58:28,347 [Thread-5] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:131 - HTTP response: HTTP/1.1 303  [Location: https://tbk.ego-mv.de/BuergerKontoWeb/SAMLReceiveServlet?sessionId=261c0d84-46bd-4ed5-9c76-a1b3e6e17c14&ResultMajor=error&ResultMinor=http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#incorrectParameter]
779
2014-11-22 12:58:39,316 [Open-eCard Localhost-Binding] ERROR o.o.c.binding.http.HTTPService:138 - socket closed
780
java.net.SocketException: socket closed
781
	at java.net.DualStackPlainSocketImpl.accept0(Native Method) ~[na:1.8.0_25]
782
	at java.net.DualStackPlainSocketImpl.socketAccept(Unknown Source) ~[na:1.8.0_25]
783
	at java.net.AbstractPlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_25]
784
	at java.net.PlainSocketImpl.accept(Unknown Source) ~[na:1.8.0_25]
785
	at java.net.ServerSocket.implAccept(Unknown Source) ~[na:1.8.0_25]
786
	at java.net.ServerSocket.accept(Unknown Source) ~[na:1.8.0_25]
787
	at org.openecard.control.binding.http.HTTPService.run(HTTPService.java:117) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
788
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
789
2014-11-22 12:58:39,317 [pool-2-thread-2] WARN  org.openecard.ifd.scio.IFD:482 - null
790
java.lang.InterruptedException: null
791
	at java.util.concurrent.FutureTask.awaitDone(Unknown Source) [na:1.8.0_25]
792
	at java.util.concurrent.FutureTask.get(Unknown Source) [na:1.8.0_25]
793
	at org.openecard.ifd.scio.IFD.wait(IFD.java:467) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
794
	at org.openecard.event.WaitFuture.call(WaitFuture.java:54) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
795
	at org.openecard.event.WaitFuture.call(WaitFuture.java:39) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
796
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
797
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
798
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
799
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
800
2014-11-22 12:58:39,317 [pool-1-thread-1] WARN  o.openecard.ifd.scio.EventListener:140 - null
801
java.lang.InterruptedException: null
802
	at java.util.concurrent.FutureTask.awaitDone(Unknown Source) [na:1.8.0_25]
803
	at java.util.concurrent.FutureTask.get(Unknown Source) [na:1.8.0_25]
804
	at org.openecard.ifd.scio.EventListener.waitForEvent(EventListener.java:173) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
805
	at org.openecard.ifd.scio.EventListener.call(EventListener.java:128) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
806
	at org.openecard.ifd.scio.EventListener.call(EventListener.java:54) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
807
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
808
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
809
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
810
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
811
2014-11-22 12:58:39,319 [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.
812
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError ==> Unknown eCard exception occurred.
813
	at org.openecard.common.ECardException.makeException(ECardException.java:64) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
814
	at org.openecard.common.WSHelper$WSException.<init>(WSHelper.java:46) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
815
	at org.openecard.common.WSHelper.checkResult(WSHelper.java:68) ~[richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
816
	at org.openecard.event.WaitFuture.call(WaitFuture.java:56) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
817
	at org.openecard.event.WaitFuture.call(WaitFuture.java:39) [richclient-1.1.0-20141121-bundle-cifs-session.jar:na]
818
	at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.8.0_25]
819
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.8.0_25]
820
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.8.0_25]
821
	at java.lang.Thread.run(Unknown Source) [na:1.8.0_25]
(1-1/2)