Project

General

Profile

Bug #677 » richclient_info.log

richclient_info.log - Hermann Radda, 08/27/2018 01:36 PM

 
1
2018-08-27 09:32:44,500 [Addon-File-Watcher] ERROR o.o.addon.AddonFileSystemMonitor:-1 - The jar file conf does not seem to be an add-on.
2
2018-08-27 09:33:15,553 [AWT-EventQueue-1] INFO  o.o.richclient.LogbackConfig:-1 - Configured Logback with config file from: /home/hermann/.openecard/richclient_logback.xml
3
2018-08-27 09:33:26,803 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card insert event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
4
2018-08-27 09:33:26,804 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_INSERTED
5
2018-08-27 09:33:26,805 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@58e583f3
6
2018-08-27 09:33:26,805 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@5622669f
7
2018-08-27 09:33:26,805 [pool-2-thread-4] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
8
2018-08-27 09:33:26,821 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
9
2018-08-27 09:33:26,821 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=true
10
2018-08-27 09:33:26,823 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
11
2018-08-27 09:33:26,904 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
12
2018-08-27 09:33:26,905 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
13
2018-08-27 09:33:26,911 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
14
2018-08-27 09:33:26,915 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
15
2018-08-27 09:33:26,923 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
16
2018-08-27 09:33:26,924 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
17
2018-08-27 09:33:26,934 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
18
2018-08-27 09:33:26,935 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
19
2018-08-27 09:33:26,940 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
20
2018-08-27 09:33:26,941 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
21
2018-08-27 09:33:26,949 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
22
2018-08-27 09:33:26,951 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
23
2018-08-27 09:33:26,957 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
24
2018-08-27 09:33:26,958 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
25
2018-08-27 09:33:26,966 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
26
2018-08-27 09:33:26,966 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
27
2018-08-27 09:33:26,975 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
28
2018-08-27 09:33:26,976 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
29
2018-08-27 09:33:26,980 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
30
2018-08-27 09:33:26,982 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
31
2018-08-27 09:33:26,995 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
32
2018-08-27 09:33:26,995 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
33
2018-08-27 09:33:27,006 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
34
2018-08-27 09:33:27,007 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
35
2018-08-27 09:33:27,017 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
36
2018-08-27 09:33:27,018 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
37
2018-08-27 09:33:27,029 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
38
2018-08-27 09:33:27,030 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
39
2018-08-27 09:33:27,039 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
40
2018-08-27 09:33:27,040 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
41
2018-08-27 09:33:27,052 [pool-2-thread-4] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - 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 
42
2018-08-27 09:33:27,063 [pool-2-thread-4] DEBUG org.openecard.event.Recognizer:-1 - Found a recognized card event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
43
2018-08-27 09:33:27,063 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_RECOGNIZED
44
2018-08-27 09:33:27,063 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@6e493cdd
45
2018-08-27 09:33:27,064 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@1b7a75f0
46
2018-08-27 09:33:27,064 [pool-2-thread-3] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
47
2018-08-27 09:33:27,065 [pool-2-thread-2] INFO  o.o.c.sal.state.SALStateCallback:-1 - Add ConnectionHandle to SAL:
48
ConnectionHandle:
49
  Session: -e551M6ztvikAbMknmYPxA
50
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
51
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
52
  CardType: http://bsi.bund.de/cif/npa.xml
53
2018-08-27 09:33:58,225 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - HTTP request: GET /eID-Client?tcTokenURL=https%3A%2F%2Fnpa.allianz.de%2Fazsecurity-npa-service%2FNpaEIDService%2Fnparef%2FIIdz75Fd0lt3kfi1IUDJ0lE [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.0) Gecko/20100101 Firefox/60.0, Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8, Accept-Language: en-GB,en;q=0.5, Accept-Encoding: gzip, deflate, DNT: 1, Connection: keep-alive, Upgrade-Insecure-Requests: 1]
54
2018-08-27 09:33:58,308 [Thread-7] WARN  org.openecard.common.I18n:-1 - Failed to load resource tr03112 for lang de_DE.
55
2018-08-27 09:33:58,316 [Thread-7] WARN  org.openecard.common.I18n:-1 - Failed to load resource tctoken for lang de_DE.
56
2018-08-27 09:33:58,317 [Thread-7] WARN  org.openecard.common.I18n:-1 - Failed to load resource pace for lang de_DE.
57
2018-08-27 09:33:58,467 [Thread-7] INFO  o.o.binding.tctoken.ResourceContext:-1 - Trying to load resource from: https://npa.allianz.de/azsecurity-npa-service/NpaEIDService/nparef/IIdz75Fd0lt3kfi1IUDJ0lE
58
2018-08-27 09:33:58,529 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
59
2018-08-27 09:33:58,532 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
60
2018-08-27 09:33:58,624 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Performing TLS handshake.
61
2018-08-27 09:33:58,875 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [npa.allianz.de] [npa.allianz.de]
62
2018-08-27 09:33:59,183 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - TLS handshake performed.
63
2018-08-27 09:33:59,280 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request:
64
  GET /azsecurity-npa-service/NpaEIDService/nparef/IIdz75Fd0lt3kfi1IUDJ0lE HTTP/1.1
65
  Connection: keep-alive
66
  User-Agent: Open-eCard-App/1.2.4
67
  Host: npa.allianz.de
68
  Accept: text/xml, */*;q=0.8
69
  Accept-Charset: utf-8, *;q=0.8
70

    
71
2018-08-27 09:33:59,281 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Sending HTTP request.
72
2018-08-27 09:33:59,462 [Thread-7] DEBUG o.o.t.h.cookies.CookieManager:-1 - Setting cookie WebSessionID=95.91.255.234.1535362439370464; path=/ for key npa.allianz.de.
73
2018-08-27 09:33:59,463 [Thread-7] DEBUG o.o.t.h.cookies.CookieManager:-1 - Setting cookie JSESSIONID=0000TttYS2L_g0uYDS9LNcfA4uw:19f0bnf4s; Path=/; HttpOnly for key npa.allianz.de.
74
2018-08-27 09:33:59,463 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - HTTP response received.
75
2018-08-27 09:33:59,463 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
76
  HTTP/1.1 302 Found
77
  Date: Mon, 27 Aug 2018 09:33:59 GMT
78
  Server: Apache
79
  Set-Cookie: WebSessionID=95.91.255.234.1535362439370464; path=/
80
  X-Powered-By: Servlet/3.0
81
  Location: https://eid1.eid-service.de:443/gov_autent/async?SAMLRequest=rVZZk6LIGv0rBvNoVLELZZQ1wSKKgriggi8TLMmikCzJIvz6S3XZPdW3Z%2BZ2T9wXIsg859vOScjX3%2B9pMmpAieIMzjDymcBGAHqZH8Nwhh1N5YnHfn97RU6aUPlUqKsI7kFRA1SNBiJE04%2BdGVaXcJo5KEZT6KQATStvehB0bUo9E9O8zKrMyxJsJCAEympIJWUQ1SkoD6BsYg8c99oMi6oqR1Mch7nz7CRJ7MD%2B2Qe40yPg1WVcdU%2FDzhP6YOCb3Jmr8oP%2FzilBgCeNFTPZoZ%2BQzTK0buXenhiZKGIjeag4hk71pcuviUDsk8%2FD42vMIduUYWg8zJo%2FnLoCsMId1EEPGylZ6YEv3c%2BwqqwBNlLlGfYHQVOBNwH8xGEZmnVpQL1QgOMJ33N8l6T8AYa2DkJxA2ZY4CTonYhQDVSIKgdWM4wiSP6J4J8oziRepjQ9ZV%2BemQl9wUbbx9DEGH6I8U8Tdj9AaLo0ze3THvhxCbwKG52%2BCjuAsIeM0y8VlJ%2F1%2B%2BfgzlfRsLe%2FkWh4TbIwhq%2F45wzfbDO%2FD7N8rwO9vQ7jns6hV3Z5BfzPfvoGehQ2AD%2BUGtK5KH52a%2Bi%2F5wKqjA%2Bt3AeX%2FhlIdirnwXvf%2BEZs2%2Fa5pZ%2BzMsQpgiBxgsEH1ID4DRuZXQ5%2BAjhPQDo44b9SDnXqoIoyfyQkYTaYM0p%2FIpYDEEnxT547WOrXqsXfXn00XYNOhUH24Prob5gETry8M30Uh7%2F9MKshyC8m%2F3%2B0XiLnKXNA%2FpSGAZljX9qR43AQ%2FieDfdcUihwS%2B7UxDBPE%2F7KNR3dSnEegfDfSdwsnJ6nBW9pKpBBLiW7QAWXImsnl98ma9qnedG0zQYtJ3lUSRy%2B259PFnrdmz8ucK0oEkFwqWjad6bnmaut3ZaNl%2FCXiQZF7C8VuFlJvpi4jbEn34h6D2rvONdlsDFFYZYyb8UqfbRl1UoEr3yVSFy50TTlvOjsoCoHueqM7S9CKitxAqkgxOrFnJolqOcC3lPpG9A6xrnb6Jq%2Fl0DSi8zLhFnOJU%2BNT4eTrsvbOmuDyE8IXc%2FZgtyfXyTQRN%2F0VzY3LZcmbtBru2iBm8z3pV60JTWOoVSeoW7OLLyy%2BM4ZPmeWmV64slPPdKMvmlpV4m2j61bZOe76Nril4sZAEmv6QAoG0kzLtNm0FFMloZ7OHJJ9H%2Fd3Shxz4DwYe1v48Dj8I%2BO%2Fs%2FbmIImj5c9EqXWQ7KstpLnuozLVV92REr%2FjYBHt7nOMcCX36HGsLYasxl3Yvh76UrheXMko3Y9hek74iPJ04OLv99UCpgrJgjorFxDZs%2BqI1SeV%2BpJO4QlxE1eE43p3g1rr4ZxqGuidZTGgEUgLwKrMzhgSX5Rz3JqeVJonLi0JfI%2B6mviD7JQS%2Ba%2BDgtOaOsdfvBWqpHFi7W%2BYbfHJvSX9N1yuuZlmLzcfFbXkizvp9sObKKZn7y80%2BWHd3LyQCvWDLDZx3eH24qs2mSPdguSCPghLUB4nkw8qqr93ubmbalS22417WKMUpFK0634p2ws4v1GTpGSuXv27pBM09obQhROQmxH1qfgrs81ktduz12FmElsWTwLPldMu5Wzzh5Limb5QtJnZww8VkvCPvG0kkzsy9P3bItfcXXMDJtTFxYblku8tYPgM9A6g9kGKnzVM6yvWUW0BRvcDddqGkgDlXeRJnZXFGhFHezMky5ddVzzXaPtdXimxxyLrq8No1rUNuHWlbH849uWRYVIjiVaJPktTyomALILl53d2YZ2pS8YU5WTnsFsCLdJE9eWPN3ZBe1yxHcQKAmlal8xyGjGhsC4r26pwgx2HS2Apdi5dm3brXXVI7elPgcmbIC5W%2BdaAR7Yja0e1aUfUgPFoNHuTKRpfzsR4Hg4aFMe5wyzqxS7aV1kYSErIgR21wFrtd77h4X7rXeTrHOfkagKTKtjvjcrNUyvcuL7u%2B9ZiDzh1gTy0SHlr5bplpFrte3%2FrxWO9vqSjmtEuajpULkcpqBR%2FaKBhQfPKvzuVj8X%2F%2F4d8e94Tvbwb4X1wy3%2F4D&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&Signature=TrajV868Xjz2fYzgKoZSmGQ5F7U95Bv3QnQHuV%2B8yb%2FP5K9%2BIhdBjfNTVfjh7xtcwWVddlDEaBkCxMUJ%2BbzbI0%2FmfPI2qryiHChysmLdG%2FWqiSLvCc%2FeXOdTaHMf2dd%2BgMbfxWoyPuN7%2FFrUqUyLwpuNOoNSJQ4jNfCzbSniLtm%2Bdv1Iwlk0Olxf%2FSEj%2FksbaKbhVr0aNjLoU8W8ReRK0yOnnY6kzKLldvHfmIMH37mi1BBlVXRuFYXgqilArqKyElnhMk3Hk91wuMNwp7HsdBtSotc9x21r5WQ8FKGJvTQfHDAS35sH0oX05%2FrLPA1wSiAhA2HCYe9L%2FcvDiBxsnw%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256
82
  Content-Length: 0
83
  Set-Cookie: JSESSIONID=0000TttYS2L_g0uYDS9LNcfA4uw:19f0bnf4s; Path=/; HttpOnly
84
  Expires: Thu, 01 Dec 1994 16:00:00 GMT
85
  Cache-Control: no-cache="set-cookie, set-cookie2"
86
  Keep-Alive: timeout=2, max=10
87
  Connection: Keep-Alive
88
  Content-Type: text/plain
89
  Content-Language: en-US
90

    
91

    
92
2018-08-27 09:33:59,465 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS warning sent.
93
2018-08-27 09:33:59,466 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS(WARN): Close [close_notify=0] --> Connection closed
94
2018-08-27 09:33:59,466 [Thread-7] INFO  o.o.binding.tctoken.ResourceContext:-1 - Trying to load resource from: https://eid1.eid-service.de:443/gov_autent/async?SAMLRequest=rVZZk6LIGv0rBvNoVLELZZQ1wSKKgriggi8TLMmikCzJIvz6S3XZPdW3Z%2BZ2T9wXIsg859vOScjX3%2B9pMmpAieIMzjDymcBGAHqZH8Nwhh1N5YnHfn97RU6aUPlUqKsI7kFRA1SNBiJE04%2BdGVaXcJo5KEZT6KQATStvehB0bUo9E9O8zKrMyxJsJCAEympIJWUQ1SkoD6BsYg8c99oMi6oqR1Mch7nz7CRJ7MD%2B2Qe40yPg1WVcdU%2FDzhP6YOCb3Jmr8oP%2FzilBgCeNFTPZoZ%2BQzTK0buXenhiZKGIjeag4hk71pcuviUDsk8%2FD42vMIduUYWg8zJo%2FnLoCsMId1EEPGylZ6YEv3c%2BwqqwBNlLlGfYHQVOBNwH8xGEZmnVpQL1QgOMJ33N8l6T8AYa2DkJxA2ZY4CTonYhQDVSIKgdWM4wiSP6J4J8oziRepjQ9ZV%2BemQl9wUbbx9DEGH6I8U8Tdj9AaLo0ze3THvhxCbwKG52%2BCjuAsIeM0y8VlJ%2F1%2B%2BfgzlfRsLe%2FkWh4TbIwhq%2F45wzfbDO%2FD7N8rwO9vQ7jns6hV3Z5BfzPfvoGehQ2AD%2BUGtK5KH52a%2Bi%2F5wKqjA%2Bt3AeX%2FhlIdirnwXvf%2BEZs2%2Fa5pZ%2BzMsQpgiBxgsEH1ID4DRuZXQ5%2BAjhPQDo44b9SDnXqoIoyfyQkYTaYM0p%2FIpYDEEnxT547WOrXqsXfXn00XYNOhUH24Prob5gETry8M30Uh7%2F9MKshyC8m%2F3%2B0XiLnKXNA%2FpSGAZljX9qR43AQ%2FieDfdcUihwS%2B7UxDBPE%2F7KNR3dSnEegfDfSdwsnJ6nBW9pKpBBLiW7QAWXImsnl98ma9qnedG0zQYtJ3lUSRy%2B259PFnrdmz8ucK0oEkFwqWjad6bnmaut3ZaNl%2FCXiQZF7C8VuFlJvpi4jbEn34h6D2rvONdlsDFFYZYyb8UqfbRl1UoEr3yVSFy50TTlvOjsoCoHueqM7S9CKitxAqkgxOrFnJolqOcC3lPpG9A6xrnb6Jq%2Fl0DSi8zLhFnOJU%2BNT4eTrsvbOmuDyE8IXc%2FZgtyfXyTQRN%2F0VzY3LZcmbtBru2iBm8z3pV60JTWOoVSeoW7OLLyy%2BM4ZPmeWmV64slPPdKMvmlpV4m2j61bZOe76Nril4sZAEmv6QAoG0kzLtNm0FFMloZ7OHJJ9H%2Fd3Shxz4DwYe1v48Dj8I%2BO%2Fs%2FbmIImj5c9EqXWQ7KstpLnuozLVV92REr%2FjYBHt7nOMcCX36HGsLYasxl3Yvh76UrheXMko3Y9hek74iPJ04OLv99UCpgrJgjorFxDZs%2BqI1SeV%2BpJO4QlxE1eE43p3g1rr4ZxqGuidZTGgEUgLwKrMzhgSX5Rz3JqeVJonLi0JfI%2B6mviD7JQS%2Ba%2BDgtOaOsdfvBWqpHFi7W%2BYbfHJvSX9N1yuuZlmLzcfFbXkizvp9sObKKZn7y80%2BWHd3LyQCvWDLDZx3eH24qs2mSPdguSCPghLUB4nkw8qqr93ubmbalS22417WKMUpFK0634p2ws4v1GTpGSuXv27pBM09obQhROQmxH1qfgrs81ktduz12FmElsWTwLPldMu5Wzzh5Limb5QtJnZww8VkvCPvG0kkzsy9P3bItfcXXMDJtTFxYblku8tYPgM9A6g9kGKnzVM6yvWUW0BRvcDddqGkgDlXeRJnZXFGhFHezMky5ddVzzXaPtdXimxxyLrq8No1rUNuHWlbH849uWRYVIjiVaJPktTyomALILl53d2YZ2pS8YU5WTnsFsCLdJE9eWPN3ZBe1yxHcQKAmlal8xyGjGhsC4r26pwgx2HS2Apdi5dm3brXXVI7elPgcmbIC5W%2BdaAR7Yja0e1aUfUgPFoNHuTKRpfzsR4Hg4aFMe5wyzqxS7aV1kYSErIgR21wFrtd77h4X7rXeTrHOfkagKTKtjvjcrNUyvcuL7u%2B9ZiDzh1gTy0SHlr5bplpFrte3%2FrxWO9vqSjmtEuajpULkcpqBR%2FaKBhQfPKvzuVj8X%2F%2F4d8e94Tvbwb4X1wy3%2F4D&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&Signature=TrajV868Xjz2fYzgKoZSmGQ5F7U95Bv3QnQHuV%2B8yb%2FP5K9%2BIhdBjfNTVfjh7xtcwWVddlDEaBkCxMUJ%2BbzbI0%2FmfPI2qryiHChysmLdG%2FWqiSLvCc%2FeXOdTaHMf2dd%2BgMbfxWoyPuN7%2FFrUqUyLwpuNOoNSJQ4jNfCzbSniLtm%2Bdv1Iwlk0Olxf%2FSEj%2FksbaKbhVr0aNjLoU8W8ReRK0yOnnY6kzKLldvHfmIMH37mi1BBlVXRuFYXgqilArqKyElnhMk3Hk91wuMNwp7HsdBtSotc9x21r5WQ8FKGJvTQfHDAS35sH0oX05%2FrLPA1wSiAhA2HCYe9L%2FcvDiBxsnw%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256
95
2018-08-27 09:33:59,467 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
96
2018-08-27 09:33:59,468 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
97
2018-08-27 09:33:59,535 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Performing TLS handshake.
98
2018-08-27 09:33:59,591 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
99
2018-08-27 09:33:59,712 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - TLS handshake performed.
100
2018-08-27 09:33:59,717 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request:
101
  GET /gov_autent/async?SAMLRequest=rVZZk6LIGv0rBvNoVLELZZQ1wSKKgriggi8TLMmikCzJIvz6S3XZPdW3Z%2BZ2T9wXIsg859vOScjX3%2B9pMmpAieIMzjDymcBGAHqZH8Nwhh1N5YnHfn97RU6aUPlUqKsI7kFRA1SNBiJE04%2BdGVaXcJo5KEZT6KQATStvehB0bUo9E9O8zKrMyxJsJCAEympIJWUQ1SkoD6BsYg8c99oMi6oqR1Mch7nz7CRJ7MD%2B2Qe40yPg1WVcdU%2FDzhP6YOCb3Jmr8oP%2FzilBgCeNFTPZoZ%2BQzTK0buXenhiZKGIjeag4hk71pcuviUDsk8%2FD42vMIduUYWg8zJo%2FnLoCsMId1EEPGylZ6YEv3c%2BwqqwBNlLlGfYHQVOBNwH8xGEZmnVpQL1QgOMJ33N8l6T8AYa2DkJxA2ZY4CTonYhQDVSIKgdWM4wiSP6J4J8oziRepjQ9ZV%2BemQl9wUbbx9DEGH6I8U8Tdj9AaLo0ze3THvhxCbwKG52%2BCjuAsIeM0y8VlJ%2F1%2B%2BfgzlfRsLe%2FkWh4TbIwhq%2F45wzfbDO%2FD7N8rwO9vQ7jns6hV3Z5BfzPfvoGehQ2AD%2BUGtK5KH52a%2Bi%2F5wKqjA%2Bt3AeX%2FhlIdirnwXvf%2BEZs2%2Fa5pZ%2BzMsQpgiBxgsEH1ID4DRuZXQ5%2BAjhPQDo44b9SDnXqoIoyfyQkYTaYM0p%2FIpYDEEnxT547WOrXqsXfXn00XYNOhUH24Prob5gETry8M30Uh7%2F9MKshyC8m%2F3%2B0XiLnKXNA%2FpSGAZljX9qR43AQ%2FieDfdcUihwS%2B7UxDBPE%2F7KNR3dSnEegfDfSdwsnJ6nBW9pKpBBLiW7QAWXImsnl98ma9qnedG0zQYtJ3lUSRy%2B259PFnrdmz8ucK0oEkFwqWjad6bnmaut3ZaNl%2FCXiQZF7C8VuFlJvpi4jbEn34h6D2rvONdlsDFFYZYyb8UqfbRl1UoEr3yVSFy50TTlvOjsoCoHueqM7S9CKitxAqkgxOrFnJolqOcC3lPpG9A6xrnb6Jq%2Fl0DSi8zLhFnOJU%2BNT4eTrsvbOmuDyE8IXc%2FZgtyfXyTQRN%2F0VzY3LZcmbtBru2iBm8z3pV60JTWOoVSeoW7OLLyy%2BM4ZPmeWmV64slPPdKMvmlpV4m2j61bZOe76Nril4sZAEmv6QAoG0kzLtNm0FFMloZ7OHJJ9H%2Fd3Shxz4DwYe1v48Dj8I%2BO%2Fs%2FbmIImj5c9EqXWQ7KstpLnuozLVV92REr%2FjYBHt7nOMcCX36HGsLYasxl3Yvh76UrheXMko3Y9hek74iPJ04OLv99UCpgrJgjorFxDZs%2BqI1SeV%2BpJO4QlxE1eE43p3g1rr4ZxqGuidZTGgEUgLwKrMzhgSX5Rz3JqeVJonLi0JfI%2B6mviD7JQS%2Ba%2BDgtOaOsdfvBWqpHFi7W%2BYbfHJvSX9N1yuuZlmLzcfFbXkizvp9sObKKZn7y80%2BWHd3LyQCvWDLDZx3eH24qs2mSPdguSCPghLUB4nkw8qqr93ubmbalS22417WKMUpFK0634p2ws4v1GTpGSuXv27pBM09obQhROQmxH1qfgrs81ktduz12FmElsWTwLPldMu5Wzzh5Limb5QtJnZww8VkvCPvG0kkzsy9P3bItfcXXMDJtTFxYblku8tYPgM9A6g9kGKnzVM6yvWUW0BRvcDddqGkgDlXeRJnZXFGhFHezMky5ddVzzXaPtdXimxxyLrq8No1rUNuHWlbH849uWRYVIjiVaJPktTyomALILl53d2YZ2pS8YU5WTnsFsCLdJE9eWPN3ZBe1yxHcQKAmlal8xyGjGhsC4r26pwgx2HS2Apdi5dm3brXXVI7elPgcmbIC5W%2BdaAR7Yja0e1aUfUgPFoNHuTKRpfzsR4Hg4aFMe5wyzqxS7aV1kYSErIgR21wFrtd77h4X7rXeTrHOfkagKTKtjvjcrNUyvcuL7u%2B9ZiDzh1gTy0SHlr5bplpFrte3%2FrxWO9vqSjmtEuajpULkcpqBR%2FaKBhQfPKvzuVj8X%2F%2F4d8e94Tvbwb4X1wy3%2F4D&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&Signature=TrajV868Xjz2fYzgKoZSmGQ5F7U95Bv3QnQHuV%2B8yb%2FP5K9%2BIhdBjfNTVfjh7xtcwWVddlDEaBkCxMUJ%2BbzbI0%2FmfPI2qryiHChysmLdG%2FWqiSLvCc%2FeXOdTaHMf2dd%2BgMbfxWoyPuN7%2FFrUqUyLwpuNOoNSJQ4jNfCzbSniLtm%2Bdv1Iwlk0Olxf%2FSEj%2FksbaKbhVr0aNjLoU8W8ReRK0yOnnY6kzKLldvHfmIMH37mi1BBlVXRuFYXgqilArqKyElnhMk3Hk91wuMNwp7HsdBtSotc9x21r5WQ8FKGJvTQfHDAS35sH0oX05%2FrLPA1wSiAhA2HCYe9L%2FcvDiBxsnw%3D%3D&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256 HTTP/1.1
102
  Connection: keep-alive
103
  User-Agent: Open-eCard-App/1.2.4
104
  Host: eid1.eid-service.de:443
105
  Accept: text/xml, */*;q=0.8
106
  Accept-Charset: utf-8, *;q=0.8
107

    
108
2018-08-27 09:33:59,718 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Sending HTTP request.
109
2018-08-27 09:33:59,784 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - HTTP response received.
110
2018-08-27 09:33:59,786 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
111
  HTTP/1.1 302 Found
112
  Cache-Control: HTTP/1.1 302 Moved Temporarilyno-cache, no-store
113
  Server: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1
114
  Content-Security-Policy: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'
115
  Pragma: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'no-cache
116
  Content-Length: 0
117
  Date: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'no-cache0Mon, 27 Aug 2018 09:33:59 GMT
118
  Location: https://eid1.eid-service.de:443/ecardpaos/startauthentication?RestrictedID=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=9fffc64b-5c05-431d-808c-6f838b9930a8&GivenNames=true&Signature=ej6W0XimAM%2ByEKuquhbRWMvEge7Fze5tTnj4YYAJ3saBSf2Wht%2BNkoXmkaewfTdFv9K3QZ0gwXsCrQFQiJSywuG1QrKilf%2BJGjOHZ24hhcrRptynDYMBURhIP7wexmAOfS%2B2OOgPc72TJm8cHiz8Qrw5Oseac47LJtMfIH557jheKfKfDAyviNkInIUpbowLvW99fgdm6q%2B5bsEF6ezCk5juG%2FZDgs5sTbqGNC0c091R8%2FChdQAyV0tfdm2GArMG%2BG3oPoiReRjHvZtJeWt090%2F11Oa1eglWiSnBX9BQghpI1ChCivSoiRNvxP%2FlXnyAclvU4GxPeOGf00CXwp2WkQ%3D%3D&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_5977811670fff6f67a91af23d78c4e8e305f20f2
119

    
120

    
121
2018-08-27 09:33:59,787 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS warning sent.
122
2018-08-27 09:33:59,788 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS(WARN): Close [close_notify=0] --> Connection closed
123
2018-08-27 09:33:59,789 [Thread-7] INFO  o.o.binding.tctoken.ResourceContext:-1 - Trying to load resource from: https://eid1.eid-service.de:443/ecardpaos/startauthentication?RestrictedID=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=9fffc64b-5c05-431d-808c-6f838b9930a8&GivenNames=true&Signature=ej6W0XimAM%2ByEKuquhbRWMvEge7Fze5tTnj4YYAJ3saBSf2Wht%2BNkoXmkaewfTdFv9K3QZ0gwXsCrQFQiJSywuG1QrKilf%2BJGjOHZ24hhcrRptynDYMBURhIP7wexmAOfS%2B2OOgPc72TJm8cHiz8Qrw5Oseac47LJtMfIH557jheKfKfDAyviNkInIUpbowLvW99fgdm6q%2B5bsEF6ezCk5juG%2FZDgs5sTbqGNC0c091R8%2FChdQAyV0tfdm2GArMG%2BG3oPoiReRjHvZtJeWt090%2F11Oa1eglWiSnBX9BQghpI1ChCivSoiRNvxP%2FlXnyAclvU4GxPeOGf00CXwp2WkQ%3D%3D&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_5977811670fff6f67a91af23d78c4e8e305f20f2
124
2018-08-27 09:33:59,789 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
125
2018-08-27 09:33:59,790 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
126
2018-08-27 09:33:59,824 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Performing TLS handshake.
127
2018-08-27 09:33:59,873 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
128
2018-08-27 09:33:59,939 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - TLS handshake performed.
129
2018-08-27 09:33:59,939 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request:
130
  GET /ecardpaos/startauthentication?RestrictedID=true&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&requestID=9fffc64b-5c05-431d-808c-6f838b9930a8&GivenNames=true&Signature=ej6W0XimAM%2ByEKuquhbRWMvEge7Fze5tTnj4YYAJ3saBSf2Wht%2BNkoXmkaewfTdFv9K3QZ0gwXsCrQFQiJSywuG1QrKilf%2BJGjOHZ24hhcrRptynDYMBURhIP7wexmAOfS%2B2OOgPc72TJm8cHiz8Qrw5Oseac47LJtMfIH557jheKfKfDAyviNkInIUpbowLvW99fgdm6q%2B5bsEF6ezCk5juG%2FZDgs5sTbqGNC0c091R8%2FChdQAyV0tfdm2GArMG%2BG3oPoiReRjHvZtJeWt090%2F11Oa1eglWiSnBX9BQghpI1ChCivSoiRNvxP%2FlXnyAclvU4GxPeOGf00CXwp2WkQ%3D%3D&FamilyNames=true&receiverUrl=https%3A%2F%2Feid2.eid-service.de%3A443%2Fecardpaos%2Fpaosreceiver&idProviderUrl=https%3A%2F%2Feid1.eid-service.de%3A443%2Fgov_autent%2Fasync%3FrefID%3D_5977811670fff6f67a91af23d78c4e8e305f20f2 HTTP/1.1
131
  Connection: keep-alive
132
  User-Agent: Open-eCard-App/1.2.4
133
  Host: eid1.eid-service.de:443
134
  Accept: text/xml, */*;q=0.8
135
  Accept-Charset: utf-8, *;q=0.8
136

    
137
2018-08-27 09:33:59,940 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Sending HTTP request.
138
2018-08-27 09:33:59,977 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - HTTP response received.
139
2018-08-27 09:33:59,979 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
140
  HTTP/1.1 200 OK
141
  Server: HTTP/1.1 200 OKApache-Coyote/1.1
142
  Content-Security-Policy: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'
143
  Content-Length: 652
144
  Date: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'652Mon, 27 Aug 2018 09:33:59 GMT
145
  Content-Type: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'652Mon, 27 Aug 2018 09:33:59 GMTtext/xml;charset=utf-8
146

    
147

    
148
2018-08-27 09:33:59,982 [Thread-7] DEBUG o.o.binding.tctoken.TCTokenContext:-1 - Cleaned up TCToken:
149
<TCTokenType>
150
<ServerAddress>https://eid2.eid-service.de:443/ecardpaos/paosreceiver</ServerAddress>
151
<SessionIdentifier>9fffc64b-5c05-431d-808c-6f838b9930a8</SessionIdentifier>
152
<RefreshAddress>https://eid1.eid-service.de:443/gov_autent/async?refID=_5977811670fff6f67a91af23d78c4e8e305f20f2</RefreshAddress>
153
<CommunicationErrorAddress/>
154
<Binding>urn:liberty:paos:2006-08</Binding>
155
<PathSecurity-Protocol>urn:ietf:rfc:4279</PathSecurity-Protocol>
156
<PathSecurity-Parameters>
157
<PSK>b25478b3a1ebed7e502c4ec69610dc6fe8c42c169dd36c51800781b2875fc77184dbd4198c79f6ac31edd9c7174df49a73f005b87102c52a3eb37f0236ccbd7d</PSK>
158
</PathSecurity-Parameters>
159
</TCTokenType>
160

    
161
2018-08-27 09:33:59,998 [Thread-7] WARN  o.openecard.ws.jaxb.JAXBMarshaller:-1 - Failed to disallow DTDs entirely.
162
2018-08-27 09:34:00,106 [Thread-7] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationPath
163
2018-08-27 09:34:00,110 [Thread-7] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationConnect
164
2018-08-27 09:34:00,113 [Thread-7] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Connect
165
2018-08-27 09:34:00,126 [Thread-7] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
166
2018-08-27 09:34:00,129 [Thread-7] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
167
2018-08-27 09:34:00,139 [Thread-7] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
168
2018-08-27 09:34:00,141 [PAOS] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS warning sent.
169
2018-08-27 09:34:00,142 [PAOS] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS(WARN): Close [close_notify=0] --> Connection closed
170
2018-08-27 09:34:00,203 [PAOS] WARN  o.openecard.ws.jaxb.JAXBMarshaller:-1 - Failed to disallow DTDs entirely.
171
2018-08-27 09:34:00,208 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Opening connection to PAOS server.
172
2018-08-27 09:34:00,213 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
173
2018-08-27 09:34:00,216 [PAOS] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
174
2018-08-27 09:34:00,375 [PAOS] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [eid2.eid-service.de] [eid2.eid-service.de]
175
2018-08-27 09:34:00,443 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Connection to PAOS server established.
176
2018-08-27 09:34:00,446 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request (before adding content):
177
  POST /ecardpaos/paosreceiver HTTP/1.1
178
  Connection: keep-alive
179
  User-Agent: Open-eCard-App/1.2.4
180
  Host: eid2.eid-service.de:443
181
  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:ACLList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList";"urn:iso:std:iso-iec:24727:tech:schema:StartSession";"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: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:GetRandom";"urn:iso:std:iso-iec:24727:tech:schema:Hash"
182
  Accept: text/xml, application/xml, application/vnd.paos+xml
183

    
184
2018-08-27 09:34:00,501 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Message sent:
185
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
186
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
187
  <Header>
188
    <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">
189
      <Version>urn:liberty:paos:2006-08</Version>
190
      <EndpointReference>
191
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
192
        <MetaData>
193
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
194
        </MetaData>
195
      </EndpointReference>
196
    </PAOS>
197
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
198
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
199
    </ReplyTo>
200
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a3f4ddaf-9c6b-4a37-b094-96657f5a08a3</MessageID>
201
  </Header>
202
  <Body>
203
    <iso:StartPAOS xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns11="http://www.w3.org/2001/04/xmlenc#" xmlns:ns12="http://ws.openecard.org/schema" xmlns:ns13="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns14="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns3="http://www.w3.org/2000/09/xmldsig#" xmlns:ns4="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns5="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns6="http://www.setcce.org/schemas/ers" xmlns:ns7="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#" xmlns:ns8="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns9="urn:oasis:names:tc:SAML:1.0:assertion" Profile="http://www.bsi.bund.de/ecard/api/1.1">
204
      <iso:SessionIdentifier>9fffc64b-5c05-431d-808c-6f838b9930a8</iso:SessionIdentifier>
205
      <iso:ConnectionHandle>
206
        <iso:ChannelHandle>
207
          <iso:SessionIdentifier>-e551M6ztvikAbMknmYPxA</iso:SessionIdentifier>
208
        </iso:ChannelHandle>
209
        <iso:ContextHandle>8367BCB58ABFEDD4B2F706A4D300C9C2</iso:ContextHandle>
210
        <iso:IFDName>REINER SCT cyberJack RFID standard (0101290773) 00 00</iso:IFDName>
211
        <iso:SlotIndex>0</iso:SlotIndex>
212
        <iso:CardApplication>3F00</iso:CardApplication>
213
        <iso:SlotHandle>9EEED56A3F862EB81FFADEFC69AA0CC88A59EF7FF277B4E1</iso:SlotHandle>
214
        <iso:RecognitionInfo>
215
          <iso:CardType>http://bsi.bund.de/cif/npa.xml</iso:CardType>
216
        </iso:RecognitionInfo>
217
      </iso:ConnectionHandle>
218
      <iso:UserAgent>
219
        <iso:Name>Open eCard App</iso:Name>
220
        <iso:VersionMajor>1</iso:VersionMajor>
221
        <iso:VersionMinor>2</iso:VersionMinor>
222
        <iso:VersionSubminor>4</iso:VersionSubminor>
223
      </iso:UserAgent>
224
      <iso:SupportedAPIVersions>
225
        <iso:Major>1</iso:Major>
226
        <iso:Minor>1</iso:Minor>
227
        <iso:Subminor>4</iso:Subminor>
228
      </iso:SupportedAPIVersions>
229
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14</iso:SupportedDIDProtocols>
230
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.14.2</iso:SupportedDIDProtocols>
231
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.25</iso:SupportedDIDProtocols>
232
      <iso:SupportedDIDProtocols>urn:oid:1.3.162.15480.3.0.9</iso:SupportedDIDProtocols>
233
    </iso:StartPAOS>
234
  </Body>
235
</Envelope>
236

    
237
2018-08-27 09:34:00,502 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Sending HTTP request.
238
2018-08-27 09:34:00,884 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - HTTP response received.
239
2018-08-27 09:34:00,885 [PAOS] WARN  org.openecard.transport.paos.PAOS:-1 - The PAOS endpoint sent the http status code 200 which does not conform to the PAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
240
2018-08-27 09:34:00,928 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
241
  HTTP/1.1 200 OK
242
  Server: HTTP/1.1 200 OKApache-Coyote/1.1
243
  Content-Security-Policy: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'
244
  Content-Length: 8077
245
  Date: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'8077Mon, 27 Aug 2018 09:34:00 GMT
246
  Content-Type: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'8077Mon, 27 Aug 2018 09:34:00 GMTapplication/vnd.paos+xml
247
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
248
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
249
   <soap:Header>
250
      <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id143785" refToMessageID="9fffc64b-5c05-431d-808c-6f838b9930a8"/>
251
      <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a3f4ddaf-9c6b-4a37-b094-96657f5a08a3</RelatesTo>
252
      <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:c75fcd3f-9869-4f84-bfbc-990fff59ba32</MessageID>
253
   </soap:Header>
254
   <soap:Body>
255
   <ns2:DIDAuthenticate xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#">
256
      <ns2:ConnectionHandle>
257
        <ns2:ChannelHandle>
258
          <ns2:SessionIdentifier>-e551M6ztvikAbMknmYPxA</ns2:SessionIdentifier>
259
        </ns2:ChannelHandle>
260
        <ns2:ContextHandle>8367BCB58ABFEDD4B2F706A4D300C9C2</ns2:ContextHandle>
261
        <ns2:IFDName>REINER SCT cyberJack RFID standard (0101290773) 00 00</ns2:IFDName>
262
        <ns2:SlotIndex>0</ns2:SlotIndex>
263
        <ns2:CardApplication>3F00</ns2:CardApplication>
264
        <ns2:SlotHandle>9EEED56A3F862EB81FFADEFC69AA0CC88A59EF7FF277B4E1</ns2:SlotHandle>
265
        <ns2:RecognitionInfo>
266
          <ns2:CardType>http://bsi.bund.de/cif/npa.xml</ns2:CardType>
267
        </ns2:RecognitionInfo>
268
      </ns2:ConnectionHandle>
269
      <ns2:DIDName>PIN</ns2:DIDName>
270
      <ns2:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="ns2:EAC1InputType">
271
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201487f4e8201005f2901004210444544566549444454523130313433347f494f060a04007f000702020202038641047a87dca19b38902bed042260723ca17ff3827b66e35fcbca615a269cfa03bad33a23dfd99cbc8044dee82dde3cee52c2a00ab6cbdf8475d1dd6df781f825234d5f200e44453030303034353730303239357f4c12060904007f000703010202530500000018045f25060108000802075f2406010800080208655e732d060904007f000703010302802054d45c411927380ce565297f810d2a07ed30d7240afd7a602a6008925e9f2907732d060904007f00070301030180200f36f5182a920a3756528ca0375e5e3e736ac632024345acfdad3f9fe423e8755f37407eb1f378aa899276d2a72df385f9c4383b14e034cd427ba10cfc446ce98e21ad7b9a687ae808b921514ef5c637dab50313dc37381007379518841a3e5fd05035</ns2:Certificate>
272
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130327f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a78641048925419fc7f194922cfc6b8dd25ae6a19c1b59216e6cf06270e5d75cfd64205f55cf867bbfefeefd6e680e1fd197f18ab684484901362568efc9adb5c6018d728701015f200e44454356434165494430303130337f4c12060904007f0007030102025305fc0f13ffff5f25060102010200035f24060105010200035f37404d6f08a86a4f18409f6685387dd3c6a7ff5d68ea4f7714a861bbb3bb721d05d3014adf1763c9292f715d8e94ee9b3e1b73ab1382414ebf39dfb3b0fb6c09dbeb</ns2:Certificate>
273
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130337f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a7864104241d8627338b64f20077ffd558909a096c635ddb222852038eaae642e869a40173d588f817d95db2a6a0f077ea5ee63596a20f85bc3cb176d2f98d88d90219aa8701015f200e44454356434165494430303130347f4c12060904007f0007030102025305fc0f13ffff5f25060105000901045f24060108000901045f3740313a81ed8734e7a8c45f16b55fb603e63027b7f44c2de3a8e782552d35949db221ca33bd41a01da6a1288c7885714fc3a03fa45683b75d3884930ec6738af8a0</ns2:Certificate>
274
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130347f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a786410459d1a08a2572022e17fed831982a9cb904518003cf83dabed88dbed28963768b88dba22a153558bd21ddaa99318378b7cd7ebfe849557cd43f8526abe72734538701015f200e44454356434165494430303130357f4c12060904007f0007030102025305fc0f13ffff5f25060108000601025f24060201000601025f37402fbeeae1b1ade6cfb6cab9d9b19f6a6efacb7d9701997f43cf608bf72cf496f485f691e75190dec25c672b92cf9ba917883659a6dc16793d22bbec4019c616cc</ns2:Certificate>
275
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f2181e77f4e81a05f290100420e44454356434165494430303130357f494f060a04007f00070202020203864104a1e82256002c0eed4d7b8800e0ffeb58fe66b870040ba606c914340a3725d0a1203ea1a0b17db51803375765760f7f42232066f38d2826d9d98feefedb38b2555f2010444544566549444454523130313433347f4c12060904007f0007030102025305400513ff975f25060108000701035f24060108010001015f37406265c041632eead5478a28447c493fa59bf85cdc7b3ced109ba8670a0b40e1271190c7e4a968de512b45ecc211a3b4a54fd754ae6d7e0f9235bb04127c12240c</ns2:Certificate>
276
        <ns2:CertificateDescription xmlns="urn:iso:std:iso-iec:24727:tech:schema">308202e7060a04007f00070301030101a10e0c0c442d547275737420476d6248a2181316687474703a2f2f7777772e642d74727573742e6e6574a3180c16416c6c69616e7a20446575747363686c616e64204147a418131668747470733a2f2f6e70612e616c6c69616e7a2e6465a58201eb0c8201e74e616d652c20416e7363687269667420756e6420452d4d61696c2d4164726573736520646573204469656e737465616e626965746572733a0d0a416c6c69616e7a20446575747363686c616e642041470d0a4bc3b66e6967696e73747261c39f652032380d0a3830383032204dc3bc6e6368656e0d0a47504b2d442d49542d434e532d504f5254414c534543555249545940616c6c69616e7a2e64650d0a0d0a4765736368c3a46674737a7765636b3a204c6f67696e20696e20646173204b756e64656e706f7274616c20224d65696e6520416c6c69616e7a220d0a0d0a48696e7765697320617566206469652066c3bc722064656e204469656e737465616e626965746572207a757374c3a46e646967656e205374656c6c656e2c20646965206469652045696e68616c74756e672064657220566f7273636872696674656e207a756d20446174656e73636875747a206b6f6e74726f6c6c696572656e3a0d0a4261796572697363686573204c616e646573616d742066c3bc7220446174656e73636875747a61756673696368740d0a50726f6d656e6164652032370d0a393135323220416e73626163680d0a303938312f35332d313330300d0a706f73747374656c6c65406c64612e62617965726e2e64650d0a7777772e6c64612e62617965726e2e6465a7818b318188042008198a75e92bcc93cdff9383603d686b153b1c589ed3e0096a408f5e2e711c6c042087942ed74e7bd9d8ec866d8d76cb4b8d71b5b6b4863b7d48a1586a8603b9627f0420bec791484f129717cb4f873cd808adc5feb0d10c4307a171f2a643cdd22b5f7a0420c9910fa727feebc2a638810e5258a32c53e270b12d42c44c98a64e574b0e95da</ns2:CertificateDescription>
277
        <ns2:RequiredCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000001804</ns2:RequiredCHAT>
278
        <ns2:OptionalCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000000000</ns2:OptionalCHAT>
279
        <ns2:AuthenticatedAuxiliaryData xmlns="urn:iso:std:iso-iec:24727:tech:schema">67177315060904007f00070301040253083230313830383237</ns2:AuthenticatedAuxiliaryData>
280
        <ns2:TransactionInfo xmlns="urn:iso:std:iso-iec:24727:tech:schema"/>
281
      </ns2:AuthenticationProtocolData>
282
    </ns2:DIDAuthenticate>
283
  </soap:Body>
284
</soap:Envelope>
285

    
286

    
287
2018-08-27 09:34:00,946 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Message received:
288
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
289
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
290
  <soap:Header>
291
    <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id143785" refToMessageID="9fffc64b-5c05-431d-808c-6f838b9930a8"/>
292
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:a3f4ddaf-9c6b-4a37-b094-96657f5a08a3</RelatesTo>
293
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:c75fcd3f-9869-4f84-bfbc-990fff59ba32</MessageID>
294
  </soap:Header>
295
  <soap:Body>
296
    <ns2:DIDAuthenticate xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#">
297
      <ns2:ConnectionHandle>
298
        <ns2:ChannelHandle>
299
          <ns2:SessionIdentifier>-e551M6ztvikAbMknmYPxA</ns2:SessionIdentifier>
300
        </ns2:ChannelHandle>
301
        <ns2:ContextHandle>8367BCB58ABFEDD4B2F706A4D300C9C2</ns2:ContextHandle>
302
        <ns2:IFDName>REINER SCT cyberJack RFID standard (0101290773) 00 00</ns2:IFDName>
303
        <ns2:SlotIndex>0</ns2:SlotIndex>
304
        <ns2:CardApplication>3F00</ns2:CardApplication>
305
        <ns2:SlotHandle>9EEED56A3F862EB81FFADEFC69AA0CC88A59EF7FF277B4E1</ns2:SlotHandle>
306
        <ns2:RecognitionInfo>
307
          <ns2:CardType>http://bsi.bund.de/cif/npa.xml</ns2:CardType>
308
        </ns2:RecognitionInfo>
309
      </ns2:ConnectionHandle>
310
      <ns2:DIDName>PIN</ns2:DIDName>
311
      <ns2:AuthenticationProtocolData xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" Protocol="urn:oid:1.3.162.15480.3.0.14.2" xsi:type="ns2:EAC1InputType">
312
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201487f4e8201005f2901004210444544566549444454523130313433347f494f060a04007f000702020202038641047a87dca19b38902bed042260723ca17ff3827b66e35fcbca615a269cfa03bad33a23dfd99cbc8044dee82dde3cee52c2a00ab6cbdf8475d1dd6df781f825234d5f200e44453030303034353730303239357f4c12060904007f000703010202530500000018045f25060108000802075f2406010800080208655e732d060904007f000703010302802054d45c411927380ce565297f810d2a07ed30d7240afd7a602a6008925e9f2907732d060904007f00070301030180200f36f5182a920a3756528ca0375e5e3e736ac632024345acfdad3f9fe423e8755f37407eb1f378aa899276d2a72df385f9c4383b14e034cd427ba10cfc446ce98e21ad7b9a687ae808b921514ef5c637dab50313dc37381007379518841a3e5fd05035</ns2:Certificate>
313
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130327f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a78641048925419fc7f194922cfc6b8dd25ae6a19c1b59216e6cf06270e5d75cfd64205f55cf867bbfefeefd6e680e1fd197f18ab684484901362568efc9adb5c6018d728701015f200e44454356434165494430303130337f4c12060904007f0007030102025305fc0f13ffff5f25060102010200035f24060105010200035f37404d6f08a86a4f18409f6685387dd3c6a7ff5d68ea4f7714a861bbb3bb721d05d3014adf1763c9292f715d8e94ee9b3e1b73ab1382414ebf39dfb3b0fb6c09dbeb</ns2:Certificate>
314
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130337f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a7864104241d8627338b64f20077ffd558909a096c635ddb222852038eaae642e869a40173d588f817d95db2a6a0f077ea5ee63596a20f85bc3cb176d2f98d88d90219aa8701015f200e44454356434165494430303130347f4c12060904007f0007030102025305fc0f13ffff5f25060105000901045f24060108000901045f3740313a81ed8734e7a8c45f16b55fb603e63027b7f44c2de3a8e782552d35949db221ca33bd41a01da6a1288c7885714fc3a03fa45683b75d3884930ec6738af8a0</ns2:Certificate>
315
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f218201b67f4e82016e5f290100420e44454356434165494430303130347f4982011d060a04007f000702020202038120a9fb57dba1eea9bc3e660a909d838d726e3bf623d52620282013481d1f6e537782207d5a0975fc2c3057eef67530417affe7fb8055c126dc5c6ce94a4b44f330b5d9832026dc5c6ce94a4b44f330b5d9bbd77cbf958416295cf7e1ce6bccdc18ff8c07b68441048bd2aeb9cb7e57cb2c4b482ffc81b7afb9de27e1e3bd23c23a4453bd9ace3262547ef835c3dac4fd97f8461a14611dc9c27745132ded8e545c1d54c72f0469978520a9fb57dba1eea9bc3e660a909d838d718c397aa3b561a6f7901e0e82974856a786410459d1a08a2572022e17fed831982a9cb904518003cf83dabed88dbed28963768b88dba22a153558bd21ddaa99318378b7cd7ebfe849557cd43f8526abe72734538701015f200e44454356434165494430303130357f4c12060904007f0007030102025305fc0f13ffff5f25060108000601025f24060201000601025f37402fbeeae1b1ade6cfb6cab9d9b19f6a6efacb7d9701997f43cf608bf72cf496f485f691e75190dec25c672b92cf9ba917883659a6dc16793d22bbec4019c616cc</ns2:Certificate>
316
        <ns2:Certificate xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f2181e77f4e81a05f290100420e44454356434165494430303130357f494f060a04007f00070202020203864104a1e82256002c0eed4d7b8800e0ffeb58fe66b870040ba606c914340a3725d0a1203ea1a0b17db51803375765760f7f42232066f38d2826d9d98feefedb38b2555f2010444544566549444454523130313433347f4c12060904007f0007030102025305400513ff975f25060108000701035f24060108010001015f37406265c041632eead5478a28447c493fa59bf85cdc7b3ced109ba8670a0b40e1271190c7e4a968de512b45ecc211a3b4a54fd754ae6d7e0f9235bb04127c12240c</ns2:Certificate>
317
        <ns2:CertificateDescription xmlns="urn:iso:std:iso-iec:24727:tech:schema">308202e7060a04007f00070301030101a10e0c0c442d547275737420476d6248a2181316687474703a2f2f7777772e642d74727573742e6e6574a3180c16416c6c69616e7a20446575747363686c616e64204147a418131668747470733a2f2f6e70612e616c6c69616e7a2e6465a58201eb0c8201e74e616d652c20416e7363687269667420756e6420452d4d61696c2d4164726573736520646573204469656e737465616e626965746572733a0d0a416c6c69616e7a20446575747363686c616e642041470d0a4bc3b66e6967696e73747261c39f652032380d0a3830383032204dc3bc6e6368656e0d0a47504b2d442d49542d434e532d504f5254414c534543555249545940616c6c69616e7a2e64650d0a0d0a4765736368c3a46674737a7765636b3a204c6f67696e20696e20646173204b756e64656e706f7274616c20224d65696e6520416c6c69616e7a220d0a0d0a48696e7765697320617566206469652066c3bc722064656e204469656e737465616e626965746572207a757374c3a46e646967656e205374656c6c656e2c20646965206469652045696e68616c74756e672064657220566f7273636872696674656e207a756d20446174656e73636875747a206b6f6e74726f6c6c696572656e3a0d0a4261796572697363686573204c616e646573616d742066c3bc7220446174656e73636875747a61756673696368740d0a50726f6d656e6164652032370d0a393135323220416e73626163680d0a303938312f35332d313330300d0a706f73747374656c6c65406c64612e62617965726e2e64650d0a7777772e6c64612e62617965726e2e6465a7818b318188042008198a75e92bcc93cdff9383603d686b153b1c589ed3e0096a408f5e2e711c6c042087942ed74e7bd9d8ec866d8d76cb4b8d71b5b6b4863b7d48a1586a8603b9627f0420bec791484f129717cb4f873cd808adc5feb0d10c4307a171f2a643cdd22b5f7a0420c9910fa727feebc2a638810e5258a32c53e270b12d42c44c98a64e574b0e95da</ns2:CertificateDescription>
318
        <ns2:RequiredCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000001804</ns2:RequiredCHAT>
319
        <ns2:OptionalCHAT xmlns="urn:iso:std:iso-iec:24727:tech:schema">7f4c12060904007f00070301020253050000000000</ns2:OptionalCHAT>
320
        <ns2:AuthenticatedAuxiliaryData xmlns="urn:iso:std:iso-iec:24727:tech:schema">67177315060904007f00070301040253083230313830383237</ns2:AuthenticatedAuxiliaryData>
321
        <ns2:TransactionInfo xmlns="urn:iso:std:iso-iec:24727:tech:schema"/>
322
      </ns2:AuthenticationProtocolData>
323
    </ns2:DIDAuthenticate>
324
  </soap:Body>
325
</soap:Envelope>
326

    
327
2018-08-27 09:34:00,953 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.DIDAuthenticate
328
2018-08-27 09:34:01,049 [PAOS] WARN  org.openecard.common.I18n:-1 - Failed to load resource eac for lang de_DE.
329
2018-08-27 09:34:01,219 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.GetIFDCapabilities
330
2018-08-27 09:34:01,257 [PAOS] WARN  o.o.c.c.a.c.CardVerifiableCertificateChain:-1 - Verification of the certificate chain is disabled.
331
2018-08-27 09:34:01,263 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
332
2018-08-27 09:34:01,274 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
333
2018-08-27 09:34:01,275 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
334
2018-08-27 09:34:01,277 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: BEC791484F129717CB4F873CD808ADC5FEB0D10C4307A171F2A643CDD22B5F7A
335
2018-08-27 09:34:01,277 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
336
2018-08-27 09:34:01,278 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
337
2018-08-27 09:34:01,279 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: BEC791484F129717CB4F873CD808ADC5FEB0D10C4307A171F2A643CDD22B5F7A
338
2018-08-27 09:34:01,281 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
339
2018-08-27 09:34:01,282 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
340
2018-08-27 09:34:01,283 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
341
2018-08-27 09:34:01,284 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
342
2018-08-27 09:34:01,284 [PAOS] DEBUG o.o.common.util.TR03112Utils:-1 - Checking SOP for https://npa.allianz.de/azsecurity-npa-service/NpaEIDService/nparef/IIdz75Fd0lt3kfi1IUDJ0lE and https://npa.allianz.de.
343
2018-08-27 09:34:01,289 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Transmit
344
2018-08-27 09:34:01,290 [PAOS] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0x22 0xC1 0xA4 0x0F 0x80 0x0A 0x04 0x00 0x7F 0x00 0x07 0x02 0x02 0x04 0x02 0x02 0x83 0x01 0x03 
345
2018-08-27 09:34:01,373 [PAOS] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
346
2018-08-27 09:34:01,457 [EAC-GUI] WARN  org.openecard.common.I18n:-1 - Failed to load resource gui for lang de_DE.
347
2018-08-27 09:34:01,562 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:-1 - Selecting index 0, previous was -1.
348
2018-08-27 09:34:02,238 [EAC-GUI] WARN  org.openecard.common.I18n:-1 - Failed to load resource swing for lang de_DE.
349
2018-08-27 09:34:02,355 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Waiting for card to be removed.
350
2018-08-27 09:34:13,827 [AWT-EventQueue-1] DEBUG o.o.gui.swing.SwingNavigator:-1 - Received event: Button.next
351
2018-08-27 09:34:13,838 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Trying to kill background task if it exists.
352
2018-08-27 09:34:13,838 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Killing background task.
353
2018-08-27 09:34:13,839 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Card has not been removed.
354
2018-08-27 09:34:13,871 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Exchange result for step 'Anbieter'.
355
2018-08-27 09:34:13,872 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:-1 - Background task has been terminated from the Swing GUI.
356
java.lang.InterruptedException: null
357
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
358
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
359
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
360
	at org.openecard.common.util.Promise.deref(Unknown Source)
361
	at org.openecard.common.util.Promise.deref(Unknown Source)
362
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
363
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
364
	at org.openecard.gui.swing.StepFrame$1.run(Unknown Source)
365
	at java.lang.Thread.run(Thread.java:748)
366
2018-08-27 09:34:13,895 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:-1 - Selecting index 1, previous was 0.
367
2018-08-27 09:34:14,006 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Waiting for card to be removed.
368
2018-08-27 09:34:19,193 [AWT-EventQueue-1] DEBUG o.o.gui.swing.SwingNavigator:-1 - Received event: Button.next
369
2018-08-27 09:34:19,202 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Trying to kill background task if it exists.
370
2018-08-27 09:34:19,203 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Killing background task.
371
2018-08-27 09:34:19,204 [AWT-EventQueue-1] DEBUG org.openecard.gui.swing.StepFrame:-1 - Exchange result for step 'Angefragte Daten'.
372
2018-08-27 09:34:19,204 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Card has not been removed.
373
2018-08-27 09:34:19,205 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:-1 - Background task has been terminated from the Swing GUI.
374
java.lang.InterruptedException: null
375
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
376
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
377
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
378
	at org.openecard.common.util.Promise.deref(Unknown Source)
379
	at org.openecard.common.util.Promise.deref(Unknown Source)
380
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
381
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
382
	at org.openecard.gui.swing.StepFrame$1.run(Unknown Source)
383
	at java.lang.Thread.run(Thread.java:748)
384
2018-08-27 09:34:19,224 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:-1 - Selecting index 2, previous was 1.
385
2018-08-27 09:34:19,284 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Waiting for card to be removed.
386
2018-08-27 09:34:19,344 [Instant-Return-Thread] DEBUG o.o.gui.swing.SwingNavigator:-1 - Received event: Button.next
387
2018-08-27 09:34:19,348 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:-1 - Trying to kill background task if it exists.
388
2018-08-27 09:34:19,349 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:-1 - Killing background task.
389
2018-08-27 09:34:19,349 [Swing-GUI-BG-Task] DEBUG o.o.s.protocol.eac.gui.CardMonitor:-1 - Card has not been removed.
390
2018-08-27 09:34:19,349 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:-1 - Exchange result for step 'PIN-Eingabe'.
391
2018-08-27 09:34:19,349 [Swing-GUI-BG-Task] DEBUG org.openecard.gui.swing.StepFrame$1:-1 - Background task has been terminated from the Swing GUI.
392
java.lang.InterruptedException: null
393
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
394
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
395
	at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
396
	at org.openecard.common.util.Promise.deref(Unknown Source)
397
	at org.openecard.common.util.Promise.deref(Unknown Source)
398
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
399
	at org.openecard.sal.protocol.eac.gui.CardMonitor.call(Unknown Source)
400
	at org.openecard.gui.swing.StepFrame$1.run(Unknown Source)
401
	at java.lang.Thread.run(Thread.java:748)
402
2018-08-27 09:34:19,364 [pool-5-thread-1] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.EstablishChannel
403
2018-08-27 09:34:19,373 [pool-5-thread-1] DEBUG org.openecard.ifd.scio.IFD:-1 - executeCtrlCode request: 02050303157F4C12060904007F0007030102025305000000180400EB02308202E7060A04007F00070301030101A10E0C0C442D547275737420476D6248A2181316687474703A2F2F7777772E642D74727573742E6E6574A3180C16416C6C69616E7A20446575747363686C616E64204147A418131668747470733A2F2F6E70612E616C6C69616E7A2E6465A58201EB0C8201E74E616D652C20416E7363687269667420756E6420452D4D61696C2D4164726573736520646573204469656E737465616E626965746572733A0D0A416C6C69616E7A20446575747363686C616E642041470D0A4BC3B66E6967696E73747261C39F652032380D0A3830383032204DC3BC6E6368656E0D0A47504B2D442D49542D434E532D504F5254414C534543555249545940616C6C69616E7A2E64650D0A0D0A4765736368C3A46674737A7765636B3A204C6F67696E20696E20646173204B756E64656E706F7274616C20224D65696E6520416C6C69616E7A220D0A0D0A48696E7765697320617566206469652066C3BC722064656E204469656E737465616E626965746572207A757374C3A46E646967656E205374656C6C656E2C20646965206469652045696E68616C74756E672064657220566F7273636872696674656E207A756D20446174656E73636875747A206B6F6E74726F6C6C696572656E3A0D0A4261796572697363686573204C616E646573616D742066C3BC7220446174656E73636875747A61756673696368740D0A50726F6D656E6164652032370D0A393135323220416E73626163680D0A303938312F35332D313330300D0A706F73747374656C6C65406C64612E62617965726E2E64650D0A7777772E6C64612E62617965726E2E6465A7818B318188042008198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C042087942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F0420BEC791484F129717CB4F873CD808ADC5FEB0D10C4307A171F2A643CDD22B5F7A0420C9910FA727FEEBC2A638810E5258A32C53E270B12D42C44C98A64E574B0E95DA
404
2018-08-27 09:34:33,528 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card removed event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
405
2018-08-27 09:34:33,532 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_REMOVED
406
2018-08-27 09:34:33,532 [pool-2-thread-6] INFO  o.o.c.sal.state.SALStateCallback:-1 - Remove ConnectionHandle from SAL.
407
ConnectionHandle:
408
  Session: -e551M6ztvikAbMknmYPxA
409
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
410
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
411
2018-08-27 09:34:33,534 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
412
2018-08-27 09:34:33,534 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@747fa121
413
2018-08-27 09:34:33,535 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received CARD_REMOVED event.
414
2018-08-27 09:34:33,535 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
415
2018-08-27 09:34:33,535 [pool-2-thread-1] INFO  o.o.s.p.eac.gui.CardRemovedFilter:-1 - Card removed during processing of EAC GUI.
416
2018-08-27 09:34:33,535 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
417
2018-08-27 09:34:33,543 [pool-5-thread-1] ERROR o.o.s.p.eac.gui.PINStepAction:-1 - An unknown error occured while trying to verify the PIN.
418
2018-08-27 09:34:33,552 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request (before adding content):
419
  POST /ecardpaos/paosreceiver HTTP/1.1
420
  Connection: keep-alive
421
  User-Agent: Open-eCard-App/1.2.4
422
  Host: eid2.eid-service.de:443
423
  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:ACLList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationEndSession";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationList";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceDescribe";"urn:iso:std:iso-iec:24727:tech:schema:CardApplicationServiceList";"urn:iso:std:iso-iec:24727:tech:schema:StartSession";"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: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:GetRandom";"urn:iso:std:iso-iec:24727:tech:schema:Hash"
424
  Accept: text/xml, application/xml, application/vnd.paos+xml
425

    
426
2018-08-27 09:34:33,553 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Message sent:
427
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
428
<Envelope xmlns="http://schemas.xmlsoap.org/soap/envelope/">
429
  <Header>
430
    <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">
431
      <Version>urn:liberty:paos:2006-08</Version>
432
      <EndpointReference>
433
        <Address>http://www.projectliberty.org/2006/01/role/paos</Address>
434
        <MetaData>
435
          <ServiceType>http://www.bsi.bund.de/ecard/api/1.1/PAOS/GetNextCommand</ServiceType>
436
        </MetaData>
437
      </EndpointReference>
438
    </PAOS>
439
    <ReplyTo xmlns="http://www.w3.org/2005/03/addressing">
440
      <Address>http://www.projectliberty.org/2006/02/role/paos</Address>
441
    </ReplyTo>
442
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:c75fcd3f-9869-4f84-bfbc-990fff59ba32</RelatesTo>
443
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:7fc0857c-f802-491f-9d7d-9be15ff6d553</MessageID>
444
  </Header>
445
  <Body>
446
    <iso:DIDAuthenticateResponse xmlns:iso="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns10="urn:oasis:names:tc:SAML:2.0:assertion" xmlns:ns11="http://www.w3.org/2001/04/xmlenc#" xmlns:ns12="http://ws.openecard.org/schema" xmlns:ns13="http://www.w3.org/2001/04/xmldsig-more#" xmlns:ns14="http://www.w3.org/2007/05/xmldsig-more#" xmlns:ns2="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns3="http://www.w3.org/2000/09/xmldsig#" xmlns:ns4="http://www.bsi.bund.de/ecard/api/1.1" xmlns:ns5="http://uri.etsi.org/02231/v3.1.2#" xmlns:ns6="http://www.setcce.org/schemas/ers" xmlns:ns7="urn:oasis:names:tc:dss-x:1.0:profiles:verificationreport:schema#" xmlns:ns8="http://uri.etsi.org/01903/v1.3.2#" xmlns:ns9="urn:oasis:names:tc:SAML:1.0:assertion">
447
      <ns2:Result>
448
        <ns2:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns2:ResultMajor>
449
        <ns2:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns2:ResultMinor>
450
        <ns2:ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.</ns2:ResultMessage>
451
      </ns2:Result>
452
    </iso:DIDAuthenticateResponse>
453
  </Body>
454
</Envelope>
455

    
456
2018-08-27 09:34:33,554 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Sending HTTP request.
457
2018-08-27 09:34:33,554 [EAC-GUI] DEBUG org.openecard.gui.swing.StepBar:-1 - Selecting index 2, previous was 2.
458
2018-08-27 09:34:33,572 [Instant-Return-Thread] DEBUG o.o.gui.swing.SwingNavigator:-1 - Received event: Button.next
459
2018-08-27 09:34:33,587 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:-1 - Trying to kill background task if it exists.
460
2018-08-27 09:34:33,587 [Instant-Return-Thread] DEBUG org.openecard.gui.swing.StepFrame:-1 - Exchange result for step 'Fehler'.
461
2018-08-27 09:34:33,600 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
462
2018-08-27 09:34:33,602 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=false
463
2018-08-27 09:34:33,603 [EAC-GUI] INFO  o.o.gui.executor.ExecutionEngine:-1 - StepAction was canceled.
464
2018-08-27 09:34:33,862 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - HTTP response received.
465
2018-08-27 09:34:33,862 [PAOS] WARN  org.openecard.transport.paos.PAOS:-1 - The PAOS endpoint sent the http status code 200 which does not conform to the PAOS specification. (See section 9.4 Processing Rules of the PAOS Specification)
466
2018-08-27 09:34:33,862 [PAOS] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
467
  HTTP/1.1 200 OK
468
  Server: HTTP/1.1 200 OKApache-Coyote/1.1
469
  Content-Security-Policy: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'
470
  Content-Length: 1213
471
  Date: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'1213Mon, 27 Aug 2018 09:34:33 GMT
472
  Content-Type: HTTP/1.1 200 OKApache-Coyote/1.1default-src 'self'1213Mon, 27 Aug 2018 09:34:33 GMTapplication/vnd.paos+xml
473
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
474
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
475
   <soap:Header>
476
      <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id143787" refToMessageID="9fffc64b-5c05-431d-808c-6f838b9930a8"/>
477
      <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:7fc0857c-f802-491f-9d7d-9be15ff6d553</RelatesTo>
478
      <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:ff02fd56-334f-4cd9-befd-5d79de9f2f8c</MessageID>
479
   </soap:Header>
480
   <soap:Body>
481
   <ns2:StartPAOSResponse xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#">
482
      <ns3:Result>
483
        <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns3:ResultMajor>
484
        <ns3:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns3:ResultMinor>
485
        <ns3:ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.</ns3:ResultMessage>
486
      </ns3:Result>
487
    </ns2:StartPAOSResponse>
488
  </soap:Body>
489
</soap:Envelope>
490

    
491

    
492
2018-08-27 09:34:33,863 [PAOS] DEBUG org.openecard.transport.paos.PAOS:-1 - Message received:
493
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
494
<soap:Envelope xmlns:soap="http://schemas.xmlsoap.org/soap/envelope/">
495
  <soap:Header>
496
    <sb:Correlation xmlns:sb="http://urn:liberty:sb:2003-08" messageID="Id143787" refToMessageID="9fffc64b-5c05-431d-808c-6f838b9930a8"/>
497
    <RelatesTo xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:7fc0857c-f802-491f-9d7d-9be15ff6d553</RelatesTo>
498
    <MessageID xmlns="http://www.w3.org/2005/03/addressing">urn:uuid:ff02fd56-334f-4cd9-befd-5d79de9f2f8c</MessageID>
499
  </soap:Header>
500
  <soap:Body>
501
    <ns2:StartPAOSResponse xmlns:ns2="urn:iso:std:iso-iec:24727:tech:schema" xmlns:ns3="urn:oasis:names:tc:dss:1.0:core:schema" xmlns:ns4="http://www.w3.org/2000/09/xmldsig#">
502
      <ns3:Result>
503
        <ns3:ResultMajor>http://www.bsi.bund.de/ecard/api/1.1/resultmajor#error</ns3:ResultMajor>
504
        <ns3:ResultMinor>http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError</ns3:ResultMinor>
505
        <ns3:ResultMessage xml:lang="en">Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.</ns3:ResultMessage>
506
      </ns3:Result>
507
    </ns2:StartPAOSResponse>
508
  </soap:Body>
509
</soap:Envelope>
510

    
511
2018-08-27 09:34:33,864 [PAOS] INFO  o.o.c.tls.ClientCertPSKTlsClient:-1 - TLS warning sent.
512
2018-08-27 09:34:33,864 [PAOS] INFO  o.o.c.tls.ClientCertPSKTlsClient:-1 - TLS(WARN): Close [close_notify=0] --> Connection closed
513
2018-08-27 09:34:33,865 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.CardApplicationDisconnect
514
2018-08-27 09:34:33,865 [PAOS] DEBUG o.o.t.dispatcher.MessageDispatcher:-1 - Delivering message of type: iso.std.iso_iec._24727.tech.schema.Disconnect
515
2018-08-27 09:34:33,865 [PAOS] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
516
2018-08-27 09:34:33,865 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_REMOVED
517
2018-08-27 09:34:33,865 [pool-2-thread-6] INFO  o.o.c.sal.state.SALStateCallback:-1 - Remove ConnectionHandle from SAL.
518
ConnectionHandle:
519
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
520
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
521
2018-08-27 09:34:33,865 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@5fe49bb1
522
2018-08-27 09:34:33,866 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
523
2018-08-27 09:34:33,866 [pool-2-thread-7] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
524
2018-08-27 09:34:33,866 [PAOS] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received CARD_REMOVED event.
525
2018-08-27 09:34:33,866 [PAOS] INFO  o.o.s.p.eac.gui.CardRemovedFilter:-1 - Card removed during processing of EAC GUI.
526
2018-08-27 09:34:33,874 [PAOS] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
527
2018-08-27 09:34:33,874 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_INSERTED
528
2018-08-27 09:34:33,876 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@2f18bea8
529
2018-08-27 09:34:33,877 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@21df453b
530
2018-08-27 09:34:33,877 [pool-2-thread-6] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
531
2018-08-27 09:34:33,892 [Thread-7] ERROR o.o.binding.tctoken.TCTokenHandler:-1 - org.openecard.transport.paos.PAOSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
532
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
533
java.util.concurrent.ExecutionException: org.openecard.transport.paos.PAOSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
534
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
535
	at java.util.concurrent.FutureTask.report(FutureTask.java:122)
536
	at java.util.concurrent.FutureTask.get(FutureTask.java:192)
537
	at org.openecard.binding.tctoken.TCTokenHandler.waitForTask(Unknown Source)
538
	at org.openecard.binding.tctoken.TCTokenHandler.processBinding(Unknown Source)
539
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(Unknown Source)
540
	at org.openecard.binding.tctoken.ActivationAction.processTcTokenOrActivationObject(Unknown Source)
541
	at org.openecard.binding.tctoken.ActivationAction.processRequest(Unknown Source)
542
	at org.openecard.binding.tctoken.ActivationAction.checkRequestParameters(Unknown Source)
543
	at org.openecard.binding.tctoken.ActivationAction.execute(Unknown Source)
544
	at org.openecard.addon.bind.AppPluginActionProxy.execute(Unknown Source)
545
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(Unknown Source)
546
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java)
547
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
548
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
549
Caused by: org.openecard.transport.paos.PAOSException: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
550
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
551
	at org.openecard.transport.paos.PAOS.sendStartPAOS(Unknown Source)
552
	at org.openecard.binding.tctoken.PAOSTask.call(Unknown Source)
553
	at org.openecard.binding.tctoken.PAOSTask.call(Unknown Source)
554
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
555
	at java.lang.Thread.run(Thread.java:748)
556
Caused by: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
557
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
558
	at org.openecard.common.ECardException.makeException(Unknown Source)
559
	at org.openecard.common.WSHelper$WSException.<init>(Unknown Source)
560
	at org.openecard.common.WSHelper.checkResult(Unknown Source)
561
	... 5 common frames omitted
562
2018-08-27 09:34:33,892 [ResetRecoginitonThread] WARN  org.openecard.ifd.scio.IFD:-1 - Unknown error in the underlying SCIO implementation.
563
org.openecard.common.ifd.scio.SCIOException: Failed to connect the card in terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
564
	at org.openecard.scio.PCSCTerminal.connect(Unknown Source)
565
	at org.openecard.ifd.scio.wrapper.ChannelManager.openChannel(Unknown Source)
566
	at org.openecard.ifd.scio.IFD.connect(Unknown Source)
567
	at org.openecard.recognition.CardRecognition.connect(Unknown Source)
568
	at org.openecard.recognition.CardRecognition.recognizeCard(Unknown Source)
569
	at org.openecard.event.Recognizer.recognizeSlot(Unknown Source)
570
	at org.openecard.event.Recognizer.run(Unknown Source)
571
	at java.lang.Thread.run(Thread.java:748)
572
Caused by: javax.smartcardio.CardException: connect() failed
573
	at sun.security.smartcardio.TerminalImpl.connect(TerminalImpl.java:84)
574
	... 8 common frames omitted
575
Caused by: sun.security.smartcardio.PCSCException: SCARD_W_UNPOWERED_CARD
576
	at sun.security.smartcardio.PCSC.SCardConnect(Native Method)
577
	at sun.security.smartcardio.CardImpl.<init>(CardImpl.java:96)
578
	at sun.security.smartcardio.TerminalImpl.connect(TerminalImpl.java:78)
579
	... 8 common frames omitted
580
2018-08-27 09:34:33,893 [Thread-7] ERROR o.o.binding.tctoken.TCTokenHandler:-1 - org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
581
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
582
org.openecard.transport.paos.PAOSException: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
583
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
584
	at org.openecard.transport.paos.PAOS.sendStartPAOS(Unknown Source)
585
	at org.openecard.binding.tctoken.PAOSTask.call(Unknown Source)
586
	at org.openecard.binding.tctoken.PAOSTask.call(Unknown Source)
587
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
588
	at java.lang.Thread.run(Thread.java:748)
589
Caused by: org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
590
  ==> Failed to transmit control command to the terminal 'REINER SCT cyberJack RFID standard (0101290773) 00 00'.
591
	at org.openecard.common.ECardException.makeException(Unknown Source)
592
	at org.openecard.common.WSHelper$WSException.<init>(Unknown Source)
593
	at org.openecard.common.WSHelper.checkResult(Unknown Source)
594
	... 5 common frames omitted
595
2018-08-27 09:34:33,899 [Thread-7] INFO  o.o.binding.tctoken.ResourceContext:-1 - Trying to load resource from: https://eid1.eid-service.de:443/gov_autent/async?refID=_5977811670fff6f67a91af23d78c4e8e305f20f2
596
2018-08-27 09:34:33,901 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
597
2018-08-27 09:34:33,902 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
598
2018-08-27 09:34:33,934 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Performing TLS handshake.
599
2018-08-27 09:34:33,985 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [eid1.eid-service.de] [eid1.eid-service.de]
600
2018-08-27 09:34:34,043 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - TLS handshake performed.
601
2018-08-27 09:34:34,048 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
602
2018-08-27 09:34:34,049 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
603
2018-08-27 09:34:34,050 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - Checking SOP for https://eid1.eid-service.de:443/gov_autent/async?refID=_5977811670fff6f67a91af23d78c4e8e305f20f2 and https://npa.allianz.de.
604
2018-08-27 09:34:34,050 [Thread-7] ERROR o.o.common.util.TR03112Utils:-1 - SOP violated; the hosts do not match.
605
2018-08-27 09:34:34,051 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request:
606
  GET /gov_autent/async?refID=_5977811670fff6f67a91af23d78c4e8e305f20f2 HTTP/1.1
607
  Connection: keep-alive
608
  User-Agent: Open-eCard-App/1.2.4
609
  Host: eid1.eid-service.de:443
610
  Accept: text/xml, */*;q=0.8
611
  Accept-Charset: utf-8, *;q=0.8
612

    
613
2018-08-27 09:34:34,051 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Sending HTTP request.
614
2018-08-27 09:34:34,102 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - HTTP response received.
615
2018-08-27 09:34:34,103 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
616
  HTTP/1.1 302 Found
617
  Cache-Control: HTTP/1.1 302 Moved Temporarilyno-cache, no-store
618
  Server: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1
619
  Content-Security-Policy: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'
620
  Pragma: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'no-cache
621
  Content-Length: 0
622
  Date: HTTP/1.1 302 Moved Temporarilyno-cache, no-storeApache-Coyote/1.1default-src 'self'no-cache0Mon, 27 Aug 2018 09:34:33 GMT
623
  Location: https://npa.allianz.de/azsecurity-npa-service/NpaEIDService/nparef/lvXi4oSz61vHgXkrRY6OoBB?SAMLResponse=rZdnj6vYGcff51NYs1KKrBl6c%2B69EabYxgVMM%2FBmRQfTD93Khw9zPbPZ3CibVRQLGXHK7zzl%2Fxw4X%2F42FflqCEGbVuXXF%2BQNflmFpV8FaRl%2FfTF08ZV%2B%2Bdu3L61b5Gi9UcO2rso2XC2TynbzbP360oNyU7lt2m5KtwjbTedvNPZ82qBv8KYGVVf5Vf6y4sO2S0u3%2B75Q0nV1u4Ggsnbf3DxP3fLxFoSQ%2B2hDvwdpN78uPa9tCIbUD6FL7QoHXvt4WnpAGEH5YKV4pT1IZNjHVgZUm5Sr7fZldeC%2FvvzsRb4XIQzySpJu9Ir7uPdKhwj86sO%2BR3sk5VEMvgwtP13Sq2USjKGRT4Y06RI4RnhYiDJoSNFw4LuBh6DBMqFt%2B%2FBQtp1bdl9fUBihX2H6FaV0mNlg%2BHK9IRjqvKzMz4guMXj5iN%2Fm%2B2Tw6%2BD9duzcdgnAe7xevn3GK0wD5G35%2BwzNErQNjmNfoO%2B5%2BFjhl3xpndv17Q%2BPXBWEK9PN%2B%2FC3V198XCZv2L5LStFN8zD4dKTePME%2FkryqffVAWITlu1nfJVCkZQXex20EjlX5nwVVldUX6NuHwb8m%2FWDmOWxbNw6%2FseUqBKACq9FtVyCsK9CFwSoCVbEKORcErHLYrN7Ds6hpHMc3r03fvL4M3uUU%2BssAyK1TCHlDIBC2fd59twhyc8iviqIqf%2BrLrKzGUnhf4%2B9PP1ddteqAW7ZF2q38quxAlS%2F3onDLZ18SrroQLCQ3X%2F1JFQ4XQV1pnL7yZy8EkutnK1U88Kt3lQSLBas%2FwwiMoAxMUdhfVjC8XH96%2ByECn%2B7%2B0PwpHaH0wVwvnrOfmvifZPRlWop78wuMdzv3g%2FPe8SzLjziO2FsFYgiFYQSCcWip%2BGXET0sFBEuhEBROeL7nI2QY4DTmB8sdRT3YJ3AmJGjmZaXP9aKv%2F8oT8ne5dIuyfm3ZIvlz2CVVsGLzuFq2g6T4HSw3bBGUfl3MehdY0G6O4Xwoo%2BrDw6D9DwwYgpl3%2F4I2jX%2F6wZIwWCBPnxHGowMXpcgI9ymKoJHl59IU6TMYjBJ%2B%2BH%2FxAbTuK%2FIz8e7A94BwaZ2E4D1P%2F9LwvXy%2FxY4YK2OZRFFvN%2FcGCuzygPD7wKoPHBp4kqFgswEbLXkiImt%2FbvsbXAzMxWFgOjmzhSw5ewMNOjLd7ck%2F%2FoRgf%2F0DJNcjZZ52YRODPHgEqZIf48vZibyCSQ5rraEIQSuK8iEM5Xg7TKVxOQpme6cucBsefaq7pFqSzlevTdAnsVPbgk9dyzW2ihjmluIPO1dNhV0u17cCukPaNSCuBlytKZeK77Amyy5IjylT2yeJ3THyI5kuJm37jMk8iU5%2FbC%2FHJGkZzGIDS5jhbYg1GialV3PuBkje72PPYzBOzxMR1bWGpjQt0Rn03l9U3mhaeEiOjO5syXP6JN4hhBqECouyGz%2BawL5tj4q1Q9ZokITQ0eWtjLig7NevX6Bf5eSZgn9peqbpOeiXGlv0s7T9U42%2FJ7GA1k80IpgPviLuWGQEMyefPIRd8xalydupNMWLWBVHhLK0tE4nlnJt1Tgtu57GmeFR1tElFtteQet%2BerqojEAIZBAoPWpW1vn0WEuQAJoH78LXu09W7DreyyJ%2FOiNlIuDU44bzGTCNcl2hTMZm%2BJaosRTNCuWYq08iPot0Z6d7NIp9YU1W8TDr1HasJU91NHhihUOn6lM8ZT6cdA40wfdLVtkB0vu6NNADsz0EaFdxggRU%2F0k0Rd13EkiklH1Q1oZmzQcYx%2B59RegilxiK3xDkPtUPCKxlHSILNz21ufg%2Bpy03agQ63Xz5Hks9F2G%2B9kG82N3pxHb4wDgal%2BDtGYexEEQ4Kq3ntVkr7mPQVS3raVxZRxx11fE7PdGZfGGYUzFfRDaOlGzur016fhKT0GLdrEeqva2v91iwE%2BCOHA8Gmh%2BhimYbuDpeC%2B3Gz01NwSx6R%2Fa2iGsMcpvZkBx2ec1UVxBl0Nx37JOoN15uBwx3usx6dInsFgbGyWMk37fUk%2F8oz7drGvJMsDczeY1l5hWZs9O5hTBknAd3OztySziZPjT0JD%2BJg8Nf%2FB6rIWXsetmcKwfKYyps8Ktby1J0NY%2FVXRXFzg6cga3uOmGdcEQq91XGuVjECtm5F8LoNCWTuHsS5%2BwuZb3oi5JQgjIKtikMi%2BdON4BZjHuOscTCil1EAEweWDCOK5VZLvI93ljVBFlrMucz5zCa0Zq48CSei2ptO1Z8T9fUPedJzeNvGUteEdvJbGIryyWuYjENE1291D7k9xfZLAlQjH4XZi2Am%2FPFZpyivHq4%2FSSqW5vLbN0WPUcPstD3mmMkr0%2B7fj0atOegXtDDczV4kIAmTRVAGkzUmaGQx%2ByouA5M2akbMZf04sGU%2BCSeYMimd4K157tbwhwg2zg018bWc9mYNYdJY0EQGtBFhdzIwDov21o03vhKpfhOizImBvg24LacFB60w5OIaFF8DB1WYcD%2BZhc1Odc7OsxufdHMlnvi791MXMHpfo8vWBMbyoFqJ9JCQ9wWJt5u9VEUwmX1MbJU70k8hNFYACcdG060i2U%2FTb14aC879%2BAjlnCD3fCq8ZZy2906LFDnTHkEHLcr2bFgcqCTvnCKhE6vkLODRU8itVXWCN9sJXB1YP2UWql2Qy8TVJyHphtTZOfVtBBIB4QkwzXvua0Xhzx5tMZJSvfJ7aK0TlNoYEwn%2BCOOvtTwV69A3UrKu8Rk4QJu4xliSX7yJ24cblTup2vRiA4nqbGMdaRshQQSduKdbLWeFJv9VDQ%2BnCKF8LFTCFi3bdH1ia2J3dpfTgbcWRk4S3TVgJF6uzOwS2FS8U1j1QVfGWsTWza3B00%2FRjI8yIp5GOvrlbzzIJ%2BfXsMdf6TNJmyLOS8zAG7k46YNQrkdERzAcOBhHiJwmX5mZCzaP0S12S6v%2FgNH73F3HfCm7ZZCRe4iEYjEk%2BjHLjHO2vIVuZ1aarbHKAA7rGRMtGHv4uAxtDXCXv6Y%2FS1msKjp31rbZQl4XWkBbVUxl9o8RmrraJtfn0RZWOQom0QjocE2j4%2BqnRmtMFfS0YYBdkYvGCflGpFz8bH2AC5YEzzLtT5LenFaTzR%2F8FXn5GnqcD4cn0Q6uI8uHhhkbyw5AAU6Xh7rnkdu1jVd9%2BoJnEFAX6SrHwPV87eqg4ilocB7fCuYApmsh3OFRPBeVMbRfBJvToZ0WN00jdXxtzY8kPvcYcgeM%2F3ius%2BQy6Mk4GCgMAw%2FAetA%2Bpa5v9BHKVA4jjfZnJKxE7fl9QdJ1h81k1sGyVxZdkL6yfMoRRnALFWxYiPGOqZQtFILe4RMHp4f18vBiK6Js7yaU6Svxablunn3INDjbIJH%2BVGF2jziVtmQvHt0kvxQAR9yuFuQaqer5NUkWnUTRbFzHCsYMpBchwSDvFuHEjbhtpowkXJ12uWUc%2B7qsvlQD9iDaqpwaYgxYTvnx5q0pIDFpf3lLjS0Iq3VAO%2B6mNw9KuEe02NTN%2BikMlxCu86NTJWJR2nWdUZdw59Ewt5igZFq1bRnb1GLnE%2BTHySwxywFpJWg8EwATWg2ANh1TkTvKrFBaC3QULi7Zr79v32qfHy%2FPI%2BS%2F37i%2BOeh7fPQ%2FO0f&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=N3v9D%2Bj0iL7ChPvZXU6tA6JUQ6FFR9exBf7OMZ2ZDlU5Y2jZLoPk9UWCNo%2FqXYGOu9nfKHok0zk8LFkbLrbtt6NPei2oeNFrxTZ%2BLBDojewcr94HEnfsGDai9XxZ541sFPL4juwvoqk2rB2BLzEefM%2BWV6mQMkewEvTEB1xV0A2PgoORhLKTIfl9FEfb9qF9aAJnH4cixbsbBzpOPJ%2BNZKdm7NwWIjUqhw4781AhXSJIuMCz7Keudhz0%2FhqP1sr5fxjABCQlZ89u22%2Bw0lZAXP4z6RarrftY0%2BSMZIeSl8V9PQHTuhUGNZWd2QuKF1sr8bK9X2X5qJYawpJwXgz6Kg%3D%3D
624

    
625

    
626
2018-08-27 09:34:34,103 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS warning sent.
627
2018-08-27 09:34:34,104 [Thread-7] INFO  o.o.c.t.ClientCertDefaultTlsClient:-1 - TLS(WARN): Close [close_notify=0] --> Connection closed
628
2018-08-27 09:34:34,104 [Thread-7] INFO  o.o.binding.tctoken.ResourceContext:-1 - Trying to load resource from: https://npa.allianz.de/azsecurity-npa-service/NpaEIDService/nparef/lvXi4oSz61vHgXkrRY6OoBB?SAMLResponse=rZdnj6vYGcff51NYs1KKrBl6c%2B69EabYxgVMM%2FBmRQfTD93Khw9zPbPZ3CibVRQLGXHK7zzl%2Fxw4X%2F42FflqCEGbVuXXF%2BQNflmFpV8FaRl%2FfTF08ZV%2B%2Bdu3L61b5Gi9UcO2rso2XC2TynbzbP360oNyU7lt2m5KtwjbTedvNPZ82qBv8KYGVVf5Vf6y4sO2S0u3%2B75Q0nV1u4Ggsnbf3DxP3fLxFoSQ%2B2hDvwdpN78uPa9tCIbUD6FL7QoHXvt4WnpAGEH5YKV4pT1IZNjHVgZUm5Sr7fZldeC%2FvvzsRb4XIQzySpJu9Ir7uPdKhwj86sO%2BR3sk5VEMvgwtP13Sq2USjKGRT4Y06RI4RnhYiDJoSNFw4LuBh6DBMqFt%2B%2FBQtp1bdl9fUBihX2H6FaV0mNlg%2BHK9IRjqvKzMz4guMXj5iN%2Fm%2B2Tw6%2BD9duzcdgnAe7xevn3GK0wD5G35%2BwzNErQNjmNfoO%2B5%2BFjhl3xpndv17Q%2BPXBWEK9PN%2B%2FC3V198XCZv2L5LStFN8zD4dKTePME%2FkryqffVAWITlu1nfJVCkZQXex20EjlX5nwVVldUX6NuHwb8m%2FWDmOWxbNw6%2FseUqBKACq9FtVyCsK9CFwSoCVbEKORcErHLYrN7Ds6hpHMc3r03fvL4M3uUU%2BssAyK1TCHlDIBC2fd59twhyc8iviqIqf%2BrLrKzGUnhf4%2B9PP1ddteqAW7ZF2q38quxAlS%2F3onDLZ18SrroQLCQ3X%2F1JFQ4XQV1pnL7yZy8EkutnK1U88Kt3lQSLBas%2FwwiMoAxMUdhfVjC8XH96%2ByECn%2B7%2B0PwpHaH0wVwvnrOfmvifZPRlWop78wuMdzv3g%2FPe8SzLjziO2FsFYgiFYQSCcWip%2BGXET0sFBEuhEBROeL7nI2QY4DTmB8sdRT3YJ3AmJGjmZaXP9aKv%2F8oT8ne5dIuyfm3ZIvlz2CVVsGLzuFq2g6T4HSw3bBGUfl3MehdY0G6O4Xwoo%2BrDw6D9DwwYgpl3%2F4I2jX%2F6wZIwWCBPnxHGowMXpcgI9ymKoJHl59IU6TMYjBJ%2B%2BH%2FxAbTuK%2FIz8e7A94BwaZ2E4D1P%2F9LwvXy%2FxY4YK2OZRFFvN%2FcGCuzygPD7wKoPHBp4kqFgswEbLXkiImt%2FbvsbXAzMxWFgOjmzhSw5ewMNOjLd7ck%2F%2FoRgf%2F0DJNcjZZ52YRODPHgEqZIf48vZibyCSQ5rraEIQSuK8iEM5Xg7TKVxOQpme6cucBsefaq7pFqSzlevTdAnsVPbgk9dyzW2ihjmluIPO1dNhV0u17cCukPaNSCuBlytKZeK77Amyy5IjylT2yeJ3THyI5kuJm37jMk8iU5%2FbC%2FHJGkZzGIDS5jhbYg1GialV3PuBkje72PPYzBOzxMR1bWGpjQt0Rn03l9U3mhaeEiOjO5syXP6JN4hhBqECouyGz%2BawL5tj4q1Q9ZokITQ0eWtjLig7NevX6Bf5eSZgn9peqbpOeiXGlv0s7T9U42%2FJ7GA1k80IpgPviLuWGQEMyefPIRd8xalydupNMWLWBVHhLK0tE4nlnJt1Tgtu57GmeFR1tElFtteQet%2BerqojEAIZBAoPWpW1vn0WEuQAJoH78LXu09W7DreyyJ%2FOiNlIuDU44bzGTCNcl2hTMZm%2BJaosRTNCuWYq08iPot0Z6d7NIp9YU1W8TDr1HasJU91NHhihUOn6lM8ZT6cdA40wfdLVtkB0vu6NNADsz0EaFdxggRU%2F0k0Rd13EkiklH1Q1oZmzQcYx%2B59RegilxiK3xDkPtUPCKxlHSILNz21ufg%2Bpy03agQ63Xz5Hks9F2G%2B9kG82N3pxHb4wDgal%2BDtGYexEEQ4Kq3ntVkr7mPQVS3raVxZRxx11fE7PdGZfGGYUzFfRDaOlGzur016fhKT0GLdrEeqva2v91iwE%2BCOHA8Gmh%2BhimYbuDpeC%2B3Gz01NwSx6R%2Fa2iGsMcpvZkBx2ec1UVxBl0Nx37JOoN15uBwx3usx6dInsFgbGyWMk37fUk%2F8oz7drGvJMsDczeY1l5hWZs9O5hTBknAd3OztySziZPjT0JD%2BJg8Nf%2FB6rIWXsetmcKwfKYyps8Ktby1J0NY%2FVXRXFzg6cga3uOmGdcEQq91XGuVjECtm5F8LoNCWTuHsS5%2BwuZb3oi5JQgjIKtikMi%2BdON4BZjHuOscTCil1EAEweWDCOK5VZLvI93ljVBFlrMucz5zCa0Zq48CSei2ptO1Z8T9fUPedJzeNvGUteEdvJbGIryyWuYjENE1291D7k9xfZLAlQjH4XZi2Am%2FPFZpyivHq4%2FSSqW5vLbN0WPUcPstD3mmMkr0%2B7fj0atOegXtDDczV4kIAmTRVAGkzUmaGQx%2ByouA5M2akbMZf04sGU%2BCSeYMimd4K157tbwhwg2zg018bWc9mYNYdJY0EQGtBFhdzIwDov21o03vhKpfhOizImBvg24LacFB60w5OIaFF8DB1WYcD%2BZhc1Odc7OsxufdHMlnvi791MXMHpfo8vWBMbyoFqJ9JCQ9wWJt5u9VEUwmX1MbJU70k8hNFYACcdG060i2U%2FTb14aC879%2BAjlnCD3fCq8ZZy2906LFDnTHkEHLcr2bFgcqCTvnCKhE6vkLODRU8itVXWCN9sJXB1YP2UWql2Qy8TVJyHphtTZOfVtBBIB4QkwzXvua0Xhzx5tMZJSvfJ7aK0TlNoYEwn%2BCOOvtTwV69A3UrKu8Rk4QJu4xliSX7yJ24cblTup2vRiA4nqbGMdaRshQQSduKdbLWeFJv9VDQ%2BnCKF8LFTCFi3bdH1ia2J3dpfTgbcWRk4S3TVgJF6uzOwS2FS8U1j1QVfGWsTWza3B00%2FRjI8yIp5GOvrlbzzIJ%2BfXsMdf6TNJmyLOS8zAG7k46YNQrkdERzAcOBhHiJwmX5mZCzaP0S12S6v%2FgNH73F3HfCm7ZZCRe4iEYjEk%2BjHLjHO2vIVuZ1aarbHKAA7rGRMtGHv4uAxtDXCXv6Y%2FS1msKjp31rbZQl4XWkBbVUxl9o8RmrraJtfn0RZWOQom0QjocE2j4%2BqnRmtMFfS0YYBdkYvGCflGpFz8bH2AC5YEzzLtT5LenFaTzR%2F8FXn5GnqcD4cn0Q6uI8uHhhkbyw5AAU6Xh7rnkdu1jVd9%2BoJnEFAX6SrHwPV87eqg4ilocB7fCuYApmsh3OFRPBeVMbRfBJvToZ0WN00jdXxtzY8kPvcYcgeM%2F3ius%2BQy6Mk4GCgMAw%2FAetA%2Bpa5v9BHKVA4jjfZnJKxE7fl9QdJ1h81k1sGyVxZdkL6yfMoRRnALFWxYiPGOqZQtFILe4RMHp4f18vBiK6Js7yaU6Svxablunn3INDjbIJH%2BVGF2jziVtmQvHt0kvxQAR9yuFuQaqer5NUkWnUTRbFzHCsYMpBchwSDvFuHEjbhtpowkXJ12uWUc%2B7qsvlQD9iDaqpwaYgxYTvnx5q0pIDFpf3lLjS0Iq3VAO%2B6mNw9KuEe02NTN%2BikMlxCu86NTJWJR2nWdUZdw59Ewt5igZFq1bRnb1GLnE%2BTHySwxywFpJWg8EwATWg2ANh1TkTvKrFBaC3QULi7Zr79v32qfHy%2FPI%2BS%2F37i%2BOeh7fPQ%2FO0f&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=N3v9D%2Bj0iL7ChPvZXU6tA6JUQ6FFR9exBf7OMZ2ZDlU5Y2jZLoPk9UWCNo%2FqXYGOu9nfKHok0zk8LFkbLrbtt6NPei2oeNFrxTZ%2BLBDojewcr94HEnfsGDai9XxZ541sFPL4juwvoqk2rB2BLzEefM%2BWV6mQMkewEvTEB1xV0A2PgoORhLKTIfl9FEfb9qF9aAJnH4cixbsbBzpOPJ%2BNZKdm7NwWIjUqhw4781AhXSJIuMCz7Keudhz0%2FhqP1sr5fxjABCQlZ89u22%2Bw0lZAXP4z6RarrftY0%2BSMZIeSl8V9PQHTuhUGNZWd2QuKF1sr8bK9X2X5qJYawpJwXgz6Kg%3D%3D
629
2018-08-27 09:34:34,105 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
630
2018-08-27 09:34:34,107 [Thread-7] DEBUG o.o.crypto.tls.proxy.ProxySettings:-1 - Selecting proxy: DIRECT
631
2018-08-27 09:34:34,162 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Performing TLS handshake.
632
2018-08-27 09:34:34,229 [Thread-7] DEBUG o.o.c.tls.verify.HostnameVerifier:-1 - Comparing connection hostname against certificate hostname: [npa.allianz.de] [npa.allianz.de]
633
2018-08-27 09:34:34,364 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - TLS handshake performed.
634
2018-08-27 09:34:34,365 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - Hash of the retrieved server certificate: BEC791484F129717CB4F873CD808ADC5FEB0D10C4307A171F2A643CDD22B5F7A
635
2018-08-27 09:34:34,365 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 08198A75E92BCC93CDFF9383603D686B153B1C589ED3E0096A408F5E2E711C6C
636
2018-08-27 09:34:34,366 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: 87942ED74E7BD9D8EC866D8D76CB4B8D71B5B6B4863B7D48A1586A8603B9627F
637
2018-08-27 09:34:34,366 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - CommCertificate: BEC791484F129717CB4F873CD808ADC5FEB0D10C4307A171F2A643CDD22B5F7A
638
2018-08-27 09:34:34,366 [Thread-7] DEBUG o.o.common.util.TR03112Utils:-1 - Checking SOP for https://eid1.eid-service.de:443/gov_autent/async?refID=_5977811670fff6f67a91af23d78c4e8e305f20f2 and https://npa.allianz.de.
639
2018-08-27 09:34:34,366 [Thread-7] ERROR o.o.common.util.TR03112Utils:-1 - SOP violated; the hosts do not match.
640
2018-08-27 09:34:34,367 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Request:
641
  GET /azsecurity-npa-service/NpaEIDService/nparef/lvXi4oSz61vHgXkrRY6OoBB?SAMLResponse=rZdnj6vYGcff51NYs1KKrBl6c%2B69EabYxgVMM%2FBmRQfTD93Khw9zPbPZ3CibVRQLGXHK7zzl%2Fxw4X%2F42FflqCEGbVuXXF%2BQNflmFpV8FaRl%2FfTF08ZV%2B%2Bdu3L61b5Gi9UcO2rso2XC2TynbzbP360oNyU7lt2m5KtwjbTedvNPZ82qBv8KYGVVf5Vf6y4sO2S0u3%2B75Q0nV1u4Ggsnbf3DxP3fLxFoSQ%2B2hDvwdpN78uPa9tCIbUD6FL7QoHXvt4WnpAGEH5YKV4pT1IZNjHVgZUm5Sr7fZldeC%2FvvzsRb4XIQzySpJu9Ir7uPdKhwj86sO%2BR3sk5VEMvgwtP13Sq2USjKGRT4Y06RI4RnhYiDJoSNFw4LuBh6DBMqFt%2B%2FBQtp1bdl9fUBihX2H6FaV0mNlg%2BHK9IRjqvKzMz4guMXj5iN%2Fm%2B2Tw6%2BD9duzcdgnAe7xevn3GK0wD5G35%2BwzNErQNjmNfoO%2B5%2BFjhl3xpndv17Q%2BPXBWEK9PN%2B%2FC3V198XCZv2L5LStFN8zD4dKTePME%2FkryqffVAWITlu1nfJVCkZQXex20EjlX5nwVVldUX6NuHwb8m%2FWDmOWxbNw6%2FseUqBKACq9FtVyCsK9CFwSoCVbEKORcErHLYrN7Ds6hpHMc3r03fvL4M3uUU%2BssAyK1TCHlDIBC2fd59twhyc8iviqIqf%2BrLrKzGUnhf4%2B9PP1ddteqAW7ZF2q38quxAlS%2F3onDLZ18SrroQLCQ3X%2F1JFQ4XQV1pnL7yZy8EkutnK1U88Kt3lQSLBas%2FwwiMoAxMUdhfVjC8XH96%2ByECn%2B7%2B0PwpHaH0wVwvnrOfmvifZPRlWop78wuMdzv3g%2FPe8SzLjziO2FsFYgiFYQSCcWip%2BGXET0sFBEuhEBROeL7nI2QY4DTmB8sdRT3YJ3AmJGjmZaXP9aKv%2F8oT8ne5dIuyfm3ZIvlz2CVVsGLzuFq2g6T4HSw3bBGUfl3MehdY0G6O4Xwoo%2BrDw6D9DwwYgpl3%2F4I2jX%2F6wZIwWCBPnxHGowMXpcgI9ymKoJHl59IU6TMYjBJ%2B%2BH%2FxAbTuK%2FIz8e7A94BwaZ2E4D1P%2F9LwvXy%2FxY4YK2OZRFFvN%2FcGCuzygPD7wKoPHBp4kqFgswEbLXkiImt%2FbvsbXAzMxWFgOjmzhSw5ewMNOjLd7ck%2F%2FoRgf%2F0DJNcjZZ52YRODPHgEqZIf48vZibyCSQ5rraEIQSuK8iEM5Xg7TKVxOQpme6cucBsefaq7pFqSzlevTdAnsVPbgk9dyzW2ihjmluIPO1dNhV0u17cCukPaNSCuBlytKZeK77Amyy5IjylT2yeJ3THyI5kuJm37jMk8iU5%2FbC%2FHJGkZzGIDS5jhbYg1GialV3PuBkje72PPYzBOzxMR1bWGpjQt0Rn03l9U3mhaeEiOjO5syXP6JN4hhBqECouyGz%2BawL5tj4q1Q9ZokITQ0eWtjLig7NevX6Bf5eSZgn9peqbpOeiXGlv0s7T9U42%2FJ7GA1k80IpgPviLuWGQEMyefPIRd8xalydupNMWLWBVHhLK0tE4nlnJt1Tgtu57GmeFR1tElFtteQet%2BerqojEAIZBAoPWpW1vn0WEuQAJoH78LXu09W7DreyyJ%2FOiNlIuDU44bzGTCNcl2hTMZm%2BJaosRTNCuWYq08iPot0Z6d7NIp9YU1W8TDr1HasJU91NHhihUOn6lM8ZT6cdA40wfdLVtkB0vu6NNADsz0EaFdxggRU%2F0k0Rd13EkiklH1Q1oZmzQcYx%2B59RegilxiK3xDkPtUPCKxlHSILNz21ufg%2Bpy03agQ63Xz5Hks9F2G%2B9kG82N3pxHb4wDgal%2BDtGYexEEQ4Kq3ntVkr7mPQVS3raVxZRxx11fE7PdGZfGGYUzFfRDaOlGzur016fhKT0GLdrEeqva2v91iwE%2BCOHA8Gmh%2BhimYbuDpeC%2B3Gz01NwSx6R%2Fa2iGsMcpvZkBx2ec1UVxBl0Nx37JOoN15uBwx3usx6dInsFgbGyWMk37fUk%2F8oz7drGvJMsDczeY1l5hWZs9O5hTBknAd3OztySziZPjT0JD%2BJg8Nf%2FB6rIWXsetmcKwfKYyps8Ktby1J0NY%2FVXRXFzg6cga3uOmGdcEQq91XGuVjECtm5F8LoNCWTuHsS5%2BwuZb3oi5JQgjIKtikMi%2BdON4BZjHuOscTCil1EAEweWDCOK5VZLvI93ljVBFlrMucz5zCa0Zq48CSei2ptO1Z8T9fUPedJzeNvGUteEdvJbGIryyWuYjENE1291D7k9xfZLAlQjH4XZi2Am%2FPFZpyivHq4%2FSSqW5vLbN0WPUcPstD3mmMkr0%2B7fj0atOegXtDDczV4kIAmTRVAGkzUmaGQx%2ByouA5M2akbMZf04sGU%2BCSeYMimd4K157tbwhwg2zg018bWc9mYNYdJY0EQGtBFhdzIwDov21o03vhKpfhOizImBvg24LacFB60w5OIaFF8DB1WYcD%2BZhc1Odc7OsxufdHMlnvi791MXMHpfo8vWBMbyoFqJ9JCQ9wWJt5u9VEUwmX1MbJU70k8hNFYACcdG060i2U%2FTb14aC879%2BAjlnCD3fCq8ZZy2906LFDnTHkEHLcr2bFgcqCTvnCKhE6vkLODRU8itVXWCN9sJXB1YP2UWql2Qy8TVJyHphtTZOfVtBBIB4QkwzXvua0Xhzx5tMZJSvfJ7aK0TlNoYEwn%2BCOOvtTwV69A3UrKu8Rk4QJu4xliSX7yJ24cblTup2vRiA4nqbGMdaRshQQSduKdbLWeFJv9VDQ%2BnCKF8LFTCFi3bdH1ia2J3dpfTgbcWRk4S3TVgJF6uzOwS2FS8U1j1QVfGWsTWza3B00%2FRjI8yIp5GOvrlbzzIJ%2BfXsMdf6TNJmyLOS8zAG7k46YNQrkdERzAcOBhHiJwmX5mZCzaP0S12S6v%2FgNH73F3HfCm7ZZCRe4iEYjEk%2BjHLjHO2vIVuZ1aarbHKAA7rGRMtGHv4uAxtDXCXv6Y%2FS1msKjp31rbZQl4XWkBbVUxl9o8RmrraJtfn0RZWOQom0QjocE2j4%2BqnRmtMFfS0YYBdkYvGCflGpFz8bH2AC5YEzzLtT5LenFaTzR%2F8FXn5GnqcD4cn0Q6uI8uHhhkbyw5AAU6Xh7rnkdu1jVd9%2BoJnEFAX6SrHwPV87eqg4ilocB7fCuYApmsh3OFRPBeVMbRfBJvToZ0WN00jdXxtzY8kPvcYcgeM%2F3ius%2BQy6Mk4GCgMAw%2FAetA%2Bpa5v9BHKVA4jjfZnJKxE7fl9QdJ1h81k1sGyVxZdkL6yfMoRRnALFWxYiPGOqZQtFILe4RMHp4f18vBiK6Js7yaU6Svxablunn3INDjbIJH%2BVGF2jziVtmQvHt0kvxQAR9yuFuQaqer5NUkWnUTRbFzHCsYMpBchwSDvFuHEjbhtpowkXJ12uWUc%2B7qsvlQD9iDaqpwaYgxYTvnx5q0pIDFpf3lLjS0Iq3VAO%2B6mNw9KuEe02NTN%2BikMlxCu86NTJWJR2nWdUZdw59Ewt5igZFq1bRnb1GLnE%2BTHySwxywFpJWg8EwATWg2ANh1TkTvKrFBaC3QULi7Zr79v32qfHy%2FPI%2BS%2F37i%2BOeh7fPQ%2FO0f&RelayState=IIdz75Fd0lt3kfi1IUDJ0lE&SigAlg=http%3A%2F%2Fwww.w3.org%2F2001%2F04%2Fxmldsig-more%23rsa-sha256&Signature=N3v9D%2Bj0iL7ChPvZXU6tA6JUQ6FFR9exBf7OMZ2ZDlU5Y2jZLoPk9UWCNo%2FqXYGOu9nfKHok0zk8LFkbLrbtt6NPei2oeNFrxTZ%2BLBDojewcr94HEnfsGDai9XxZ541sFPL4juwvoqk2rB2BLzEefM%2BWV6mQMkewEvTEB1xV0A2PgoORhLKTIfl9FEfb9qF9aAJnH4cixbsbBzpOPJ%2BNZKdm7NwWIjUqhw4781AhXSJIuMCz7Keudhz0%2FhqP1sr5fxjABCQlZ89u22%2Bw0lZAXP4z6RarrftY0%2BSMZIeSl8V9PQHTuhUGNZWd2QuKF1sr8bK9X2X5qJYawpJwXgz6Kg%3D%3D HTTP/1.1
642
  Connection: keep-alive
643
  User-Agent: Open-eCard-App/1.2.4
644
  Host: npa.allianz.de
645
  Accept: text/xml, */*;q=0.8
646
  Accept-Charset: utf-8, *;q=0.8
647
  Cookie: WebSessionID=95.91.255.234.1535362439370464; JSESSIONID=0000TttYS2L_g0uYDS9LNcfA4uw:19f0bnf4s
648

    
649
2018-08-27 09:34:34,367 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - Sending HTTP request.
650
2018-08-27 09:34:34,659 [Thread-7] DEBUG o.o.binding.tctoken.ResourceContext:-1 - HTTP response received.
651
2018-08-27 09:34:34,661 [Thread-7] DEBUG o.o.transport.httpcore.HttpUtils:-1 - HTTP Response:
652
  HTTP/1.1 200 OK
653
  Date: Mon, 27 Aug 2018 09:34:34 GMT
654
  Server: Apache
655
  X-Powered-By: Servlet/3.0
656
  Content-Length: 1071
657
  Keep-Alive: timeout=2, max=10
658
  Connection: Keep-Alive
659
  Content-Type: text/html;charset=ISO-8859-1
660
  Content-Language: en-US
661

    
662

    
663
2018-08-27 09:34:34,663 [Thread-7] ERROR o.openecard.binding.tctoken.TCToken:-1 - No CommunicationErrorAddress to perform a redirect provided.
664
2018-08-27 09:34:34,664 [Thread-7] ERROR o.o.binding.tctoken.TCTokenHandler:-1 - No redirect address available for an error redirect.
665
org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: No redirect address available for an error redirect.
666
	at org.openecard.binding.tctoken.TCToken.checkUrl(Unknown Source)
667
	at org.openecard.binding.tctoken.TCToken.getComErrorAddressWithParams(Unknown Source)
668
	at org.openecard.binding.tctoken.TCTokenHandler.determineRefreshURL(Unknown Source)
669
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(Unknown Source)
670
	at org.openecard.binding.tctoken.ActivationAction.processTcTokenOrActivationObject(Unknown Source)
671
	at org.openecard.binding.tctoken.ActivationAction.processRequest(Unknown Source)
672
	at org.openecard.binding.tctoken.ActivationAction.checkRequestParameters(Unknown Source)
673
	at org.openecard.binding.tctoken.ActivationAction.execute(Unknown Source)
674
	at org.openecard.addon.bind.AppPluginActionProxy.execute(Unknown Source)
675
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(Unknown Source)
676
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java)
677
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
678
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
679
2018-08-27 09:34:34,665 [Thread-7] ERROR o.o.b.tctoken.ActivationAction:-1 - org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: Keine Redirect-Adresse für eine Verbindung im Fehlerfall verfügbar.
680
2018-08-27 09:34:34,666 [Thread-7] DEBUG o.o.b.tctoken.ActivationAction:-1 - org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: Keine Redirect-Adresse für eine Verbindung im Fehlerfall verfügbar.
681
org.openecard.binding.tctoken.ex.NonGuiException: org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: Keine Redirect-Adresse für eine Verbindung im Fehlerfall verfügbar.
682
	at org.openecard.binding.tctoken.TCTokenHandler.handleActivate(Unknown Source)
683
	at org.openecard.binding.tctoken.ActivationAction.processTcTokenOrActivationObject(Unknown Source)
684
	at org.openecard.binding.tctoken.ActivationAction.processRequest(Unknown Source)
685
	at org.openecard.binding.tctoken.ActivationAction.checkRequestParameters(Unknown Source)
686
	at org.openecard.binding.tctoken.ActivationAction.execute(Unknown Source)
687
	at org.openecard.addon.bind.AppPluginActionProxy.execute(Unknown Source)
688
	at org.openecard.control.binding.http.handler.HttpAppPluginActionHandler.handle(Unknown Source)
689
	at org.openecard.apache.http.protocol.HttpService.doService(HttpService.java)
690
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
691
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
692
Caused by: org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: No redirect address available for an error redirect.
693
	at org.openecard.binding.tctoken.TCToken.checkUrl(Unknown Source)
694
	at org.openecard.binding.tctoken.TCToken.getComErrorAddressWithParams(Unknown Source)
695
	at org.openecard.binding.tctoken.TCTokenHandler.determineRefreshURL(Unknown Source)
696
	... 10 common frames omitted
697
2018-08-27 09:34:34,667 [Thread-7] DEBUG o.o.b.tctoken.ActivationAction:-1 - Returning result: 
698
BindingResult <INTERNAL_ERROR, 'org.openecard.binding.tctoken.ex.InvalidRedirectUrlException: Keine Redirect-Adresse für eine Verbindung im Fehlerfall verfügbar.'>
699

    
700
2018-08-27 09:34:34,667 [Thread-7] INFO  o.o.b.tctoken.ActivationAction:-1 - Authentication failed, redirecting to with errors attached to the URL.
701
2018-08-27 09:34:34,667 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - Recieved BindingResult with ResultCode INTERNAL_ERROR
702
2018-08-27 09:34:34,668 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - BindingResult contains no body.
703
2018-08-27 09:34:34,670 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - HTTP response: HTTP/1.1 500  [] [Content-Type: text/plain; charset=UTF-8,Content-Length: 131,Chunked: false]
704
2018-08-27 09:34:34,675 [Thread-7] DEBUG o.o.c.b.h.i.ErrorResponseInterceptor:-1 - HTTP response intercepted
705
2018-08-27 09:34:35,251 [Thread-7] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.0) Gecko/20100101 Firefox/60.0, Accept: text/css,*/*;q=0.1, Accept-Language: en-GB,en;q=0.5, Accept-Encoding: gzip, deflate, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fnpa.allianz.de%2Fazsecurity-npa-service%2FNpaEIDService%2Fnparef%2FIIdz75Fd0lt3kfi1IUDJ0lE, DNT: 1, Connection: keep-alive]
706
2018-08-27 09:34:35,254 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP request: GET /css/style.css [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.0) Gecko/20100101 Firefox/60.0, Accept: text/css,*/*;q=0.1, Accept-Language: en-GB,en;q=0.5, Accept-Encoding: gzip, deflate, Referer: http://127.0.0.1:24727/eID-Client?tcTokenURL=https%3A%2F%2Fnpa.allianz.de%2Fazsecurity-npa-service%2FNpaEIDService%2Fnparef%2FIIdz75Fd0lt3kfi1IUDJ0lE, DNT: 1, Connection: keep-alive]
707
2018-08-27 09:34:35,254 [Thread-7] DEBUG o.o.c.b.h.h.common.FileHandler:-1 - Handle file request
708
2018-08-27 09:34:35,255 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP response: HTTP/1.1 200  [Content-Type: text/css; charset=UTF-8] [Content-Type: text/css; charset=UTF-8,Chunked: false]
709
2018-08-27 09:34:35,255 [Thread-7] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP request handled by: org.openecard.control.binding.http.handler.common.FileHandler
710
2018-08-27 09:34:35,343 [Thread-8] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
711
java.nio.charset.MalformedInputException: Input length = 1
712
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
713
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
714
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
715
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
716
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
717
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
718
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
719
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
720
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
721
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
722
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
723
2018-08-27 09:34:35,357 [Thread-9] DEBUG o.o.c.b.h.h.HttpAppPluginActionHandler:-1 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.0) Gecko/20100101 Firefox/60.0, Accept: */*, Accept-Language: en-GB,en;q=0.5, Accept-Encoding: gzip, deflate, DNT: 1, Connection: keep-alive]
724
2018-08-27 09:34:35,358 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP request: GET /favicon.ico [Host: 127.0.0.1:24727, User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.0) Gecko/20100101 Firefox/60.0, Accept: */*, Accept-Language: en-GB,en;q=0.5, Accept-Encoding: gzip, deflate, DNT: 1, Connection: keep-alive]
725
2018-08-27 09:34:35,358 [Thread-9] DEBUG o.o.c.b.h.h.common.FileHandler:-1 - Handle file request
726
2018-08-27 09:34:35,359 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP response: HTTP/1.1 200  [Content-Type: image/vnd.microsoft.icon; charset=UTF-8] [Content-Type: image/vnd.microsoft.icon; charset=UTF-8,Chunked: false]
727
2018-08-27 09:34:35,359 [Thread-9] DEBUG o.o.c.b.h.h.ControlCommonHandler:-1 - HTTP request handled by: org.openecard.control.binding.http.handler.common.FileHandler
728
2018-08-27 09:34:35,383 [Thread-10] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
729
java.nio.charset.MalformedInputException: Input length = 1
730
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
731
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
732
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
733
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
734
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
735
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
736
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
737
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
738
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
739
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
740
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
741
2018-08-27 09:34:35,389 [Thread-11] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
742
java.nio.charset.MalformedInputException: Input length = 1
743
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
744
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
745
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
746
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
747
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
748
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
749
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
750
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
751
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
752
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
753
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
754
2018-08-27 09:34:35,401 [Thread-12] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
755
java.nio.charset.MalformedInputException: Input length = 1
756
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
757
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
758
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
759
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
760
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
761
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
762
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
763
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
764
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
765
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
766
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
767
2018-08-27 09:34:35,413 [Thread-13] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
768
java.nio.charset.MalformedInputException: Input length = 1
769
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
770
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
771
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
772
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
773
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
774
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
775
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
776
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
777
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
778
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
779
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
780
2018-08-27 09:34:35,418 [Thread-14] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
781
java.nio.charset.MalformedInputException: Input length = 1
782
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
783
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
784
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
785
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
786
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
787
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
788
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
789
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
790
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
791
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
792
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
793
2018-08-27 09:34:35,421 [Thread-15] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
794
java.nio.charset.MalformedInputException: Input length = 1
795
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
796
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
797
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
798
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
799
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
800
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
801
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
802
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
803
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
804
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
805
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
806
2018-08-27 09:34:35,429 [Thread-16] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
807
java.nio.charset.MalformedInputException: Input length = 1
808
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
809
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
810
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
811
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
812
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
813
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
814
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
815
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
816
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
817
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
818
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
819
2018-08-27 09:34:35,435 [Thread-17] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
820
java.nio.charset.MalformedInputException: Input length = 1
821
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
822
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
823
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
824
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
825
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
826
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
827
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
828
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
829
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
830
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
831
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
832
2018-08-27 09:34:35,438 [Thread-18] ERROR o.o.c.binding.http.HttpService$1:-1 - IO Error while processing HTTP request or response.
833
java.nio.charset.MalformedInputException: Input length = 1
834
	at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
835
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.handleDecodingResult(SessionInputBufferImpl.java)
836
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.appendDecoded(SessionInputBufferImpl.java)
837
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.lineFromReadBuffer(SessionInputBufferImpl.java)
838
	at org.openecard.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java)
839
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
840
	at org.openecard.apache.http.impl.io.DefaultHttpRequestParser.parseHead(DefaultHttpRequestParser.java)
841
	at org.openecard.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java)
842
	at org.openecard.apache.http.impl.DefaultBHttpServerConnection.receiveRequestHeader(DefaultBHttpServerConnection.java)
843
	at org.openecard.apache.http.protocol.HttpService.handleRequest(HttpService.java)
844
	at org.openecard.control.binding.http.HttpService$1.run(Unknown Source)
845
2018-08-27 09:40:40,454 [pool-1-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Waiting for PCSC system to become available again.
846
2018-08-27 09:40:40,456 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a terminal removed event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
847
2018-08-27 09:40:40,456 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
848
2018-08-27 09:40:40,456 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - Event: TERMINAL_REMOVED
849
2018-08-27 09:40:40,456 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1b547d06
850
2018-08-27 09:40:40,457 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
851
2018-08-27 09:40:40,457 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
852
2018-08-27 09:40:40,460 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - No reader available exception.
853
2018-08-27 09:40:40,461 [pool-1-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Waiting for PCSC system to become available again.
854
2018-08-27 09:40:41,962 [pool-1-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Waiting for PCSC system to become available again.
855
2018-08-27 09:40:45,087 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a terminal added event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
856
2018-08-27 09:40:45,088 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
857
2018-08-27 09:40:45,088 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - Event: TERMINAL_ADDED
858
2018-08-27 09:40:45,088 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@2c3e98fd
859
2018-08-27 09:40:45,089 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
860
2018-08-27 09:40:45,089 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
861
2018-08-27 09:40:45,109 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
862
2018-08-27 09:40:45,109 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=false
863
2018-08-27 09:40:49,622 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card insert event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
864
2018-08-27 09:40:49,622 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
865
2018-08-27 09:40:49,622 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_INSERTED
866
2018-08-27 09:40:49,623 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@11ac1676
867
2018-08-27 09:40:49,623 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@54ae8a27
868
2018-08-27 09:40:49,623 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
869
2018-08-27 09:40:49,632 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
870
2018-08-27 09:40:49,632 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
871
2018-08-27 09:40:49,632 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=true
872
2018-08-27 09:40:49,706 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
873
2018-08-27 09:40:49,707 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
874
2018-08-27 09:40:49,712 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
875
2018-08-27 09:40:49,713 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
876
2018-08-27 09:40:49,720 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
877
2018-08-27 09:40:49,721 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
878
2018-08-27 09:40:49,730 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
879
2018-08-27 09:40:49,730 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
880
2018-08-27 09:40:49,735 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
881
2018-08-27 09:40:49,735 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
882
2018-08-27 09:40:49,743 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
883
2018-08-27 09:40:49,744 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
884
2018-08-27 09:40:49,749 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
885
2018-08-27 09:40:49,750 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
886
2018-08-27 09:40:49,758 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
887
2018-08-27 09:40:49,759 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
888
2018-08-27 09:40:49,768 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
889
2018-08-27 09:40:49,768 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
890
2018-08-27 09:40:49,773 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
891
2018-08-27 09:40:49,774 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
892
2018-08-27 09:40:49,787 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
893
2018-08-27 09:40:49,788 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
894
2018-08-27 09:40:49,798 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
895
2018-08-27 09:40:49,799 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
896
2018-08-27 09:40:49,810 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
897
2018-08-27 09:40:49,811 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
898
2018-08-27 09:40:49,822 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
899
2018-08-27 09:40:49,822 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
900
2018-08-27 09:40:49,832 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
901
2018-08-27 09:40:49,832 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
902
2018-08-27 09:40:49,843 [pool-2-thread-10] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - 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 
903
2018-08-27 09:40:49,848 [pool-2-thread-10] DEBUG org.openecard.event.Recognizer:-1 - Found a recognized card event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
904
2018-08-27 09:40:49,849 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_RECOGNIZED
905
2018-08-27 09:40:49,849 [pool-2-thread-10] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
906
2018-08-27 09:40:49,849 [pool-2-thread-9] INFO  o.o.c.sal.state.SALStateCallback:-1 - Add ConnectionHandle to SAL:
907
ConnectionHandle:
908
  Session: -e551M6ztvikAbMknmYPxA
909
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
910
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
911
  CardType: http://bsi.bund.de/cif/npa.xml
912
2018-08-27 09:40:49,849 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@7a5793c
913
2018-08-27 09:40:49,850 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@76e05cc2
914
2018-08-27 09:40:49,850 [pool-2-thread-11] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
915
2018-08-27 09:40:58,727 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card removed event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
916
2018-08-27 09:40:58,728 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
917
2018-08-27 09:40:58,728 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_REMOVED
918
2018-08-27 09:40:58,728 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received CARD_REMOVED event.
919
2018-08-27 09:40:58,728 [pool-2-thread-11] INFO  o.o.c.sal.state.SALStateCallback:-1 - Remove ConnectionHandle from SAL.
920
ConnectionHandle:
921
  Session: -e551M6ztvikAbMknmYPxA
922
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
923
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
924
2018-08-27 09:40:58,728 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@274de24a
925
2018-08-27 09:40:58,728 [pool-2-thread-1] INFO  o.o.s.p.eac.gui.CardRemovedFilter:-1 - Card removed during processing of EAC GUI.
926
2018-08-27 09:40:58,728 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
927
2018-08-27 09:40:58,729 [pool-2-thread-10] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
928
2018-08-27 09:40:58,737 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
929
2018-08-27 09:40:58,737 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=false
930
2018-08-27 10:30:20,862 [pool-2-thread-1] ERROR org.openecard.ifd.scio.IFD:-1 - Unknown error during wait call.
931
java.util.NoSuchElementException: null
932
	at java.util.LinkedList.removeFirst(LinkedList.java:270)
933
	at java.util.LinkedList.remove(LinkedList.java:685)
934
	at org.openecard.scio.PCSCTerminals$PCSCWatcher.waitForChange(Unknown Source)
935
	at org.openecard.ifd.scio.EventWatcher.call(Unknown Source)
936
	at org.openecard.ifd.scio.EventWatcher.call(Unknown Source)
937
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
938
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
939
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
940
	at java.lang.Thread.run(Thread.java:748)
941
2018-08-27 10:30:20,956 [pool-2-thread-1] WARN  org.openecard.event.EventRunner:-1 - IFD Wait returned with error.
942
org.openecard.common.WSHelper$WSException: http://www.bsi.bund.de/ecard/api/1.1/resultminor/al/common#unknownError
943
  ==> Unknown error during wait call.
944
	at org.openecard.common.ECardException.makeException(Unknown Source)
945
	at org.openecard.common.WSHelper$WSException.<init>(Unknown Source)
946
	at org.openecard.common.WSHelper.checkResult(Unknown Source)
947
	at org.openecard.event.EventManager.wait(Unknown Source)
948
	at org.openecard.event.EventRunner.run(Unknown Source)
949
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
950
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
951
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
952
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
953
	at java.lang.Thread.run(Thread.java:748)
954
2018-08-27 10:30:20,958 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
955
2018-08-27 10:30:20,959 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=true
956
2018-08-27 10:30:20,959 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card insert event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
957
2018-08-27 10:30:20,959 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
958
2018-08-27 10:30:20,966 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_INSERTED
959
2018-08-27 10:30:20,966 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@6e710f98
960
2018-08-27 10:30:20,966 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@60b6fc26
961
2018-08-27 10:30:20,966 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
962
2018-08-27 10:30:21,010 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
963
2018-08-27 10:30:21,010 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=true
964
2018-08-27 10:30:21,027 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
965
2018-08-27 10:30:21,102 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
966
2018-08-27 10:30:21,102 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
967
2018-08-27 10:30:21,107 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
968
2018-08-27 10:30:21,108 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
969
2018-08-27 10:30:21,116 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
970
2018-08-27 10:30:21,116 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
971
2018-08-27 10:30:21,125 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
972
2018-08-27 10:30:21,126 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
973
2018-08-27 10:30:21,130 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
974
2018-08-27 10:30:21,130 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
975
2018-08-27 10:30:21,139 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
976
2018-08-27 10:30:21,140 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
977
2018-08-27 10:30:21,145 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
978
2018-08-27 10:30:21,145 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
979
2018-08-27 10:30:21,153 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
980
2018-08-27 10:30:21,153 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
981
2018-08-27 10:30:21,162 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
982
2018-08-27 10:30:21,162 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
983
2018-08-27 10:30:21,166 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
984
2018-08-27 10:30:21,166 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
985
2018-08-27 10:30:21,179 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
986
2018-08-27 10:30:21,179 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
987
2018-08-27 10:30:21,190 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
988
2018-08-27 10:30:21,190 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
989
2018-08-27 10:30:21,200 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
990
2018-08-27 10:30:21,201 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
991
2018-08-27 10:30:21,212 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
992
2018-08-27 10:30:21,212 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
993
2018-08-27 10:30:21,222 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
994
2018-08-27 10:30:21,222 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
995
2018-08-27 10:30:21,233 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - 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 
996
2018-08-27 10:30:21,242 [pool-2-thread-14] DEBUG org.openecard.event.Recognizer:-1 - Found a recognized card event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
997
2018-08-27 10:30:21,242 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_RECOGNIZED
998
2018-08-27 10:30:21,243 [pool-2-thread-13] INFO  o.o.c.sal.state.SALStateCallback:-1 - Add ConnectionHandle to SAL:
999
ConnectionHandle:
1000
  Session: -e551M6ztvikAbMknmYPxA
1001
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
1002
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
1003
  CardType: http://bsi.bund.de/cif/npa.xml
1004
2018-08-27 10:30:21,243 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@207939ad
1005
2018-08-27 10:30:21,243 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@2ebec3d2
1006
2018-08-27 10:30:21,243 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
1007
2018-08-27 10:30:21,243 [pool-2-thread-14] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
1008
2018-08-27 10:30:57,106 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card removed event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
1009
2018-08-27 10:30:57,106 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_REMOVED
1010
2018-08-27 10:30:57,106 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
1011
2018-08-27 10:30:57,106 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@6d047406
1012
2018-08-27 10:30:57,106 [pool-2-thread-14] INFO  o.o.c.sal.state.SALStateCallback:-1 - Remove ConnectionHandle from SAL.
1013
ConnectionHandle:
1014
  Session: -e551M6ztvikAbMknmYPxA
1015
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
1016
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
1017
2018-08-27 10:30:57,107 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received CARD_REMOVED event.
1018
2018-08-27 10:30:57,107 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
1019
2018-08-27 10:30:57,107 [pool-2-thread-1] INFO  o.o.s.p.eac.gui.CardRemovedFilter:-1 - Card removed during processing of EAC GUI.
1020
2018-08-27 10:30:57,107 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
1021
2018-08-27 10:30:57,123 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
1022
2018-08-27 10:30:57,123 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=false
1023
2018-08-27 10:30:58,822 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card insert event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
1024
2018-08-27 10:30:58,823 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
1025
2018-08-27 10:30:58,823 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_INSERTED
1026
2018-08-27 10:30:58,823 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@2b31d391
1027
2018-08-27 10:30:58,823 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@3fed122c
1028
2018-08-27 10:30:58,823 [pool-2-thread-14] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
1029
2018-08-27 10:30:58,831 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
1030
2018-08-27 10:30:58,831 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
1031
2018-08-27 10:30:58,832 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=true
1032
2018-08-27 10:30:58,906 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1033
2018-08-27 10:30:58,907 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x00 0x03 
1034
2018-08-27 10:30:58,912 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
1035
2018-08-27 10:30:58,913 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
1036
2018-08-27 10:30:58,922 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1037
2018-08-27 10:30:58,923 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
1038
2018-08-27 10:30:58,933 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1039
2018-08-27 10:30:58,934 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x04 0x04 0xFF 
1040
2018-08-27 10:30:58,938 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
1041
2018-08-27 10:30:58,939 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
1042
2018-08-27 10:30:58,948 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1043
2018-08-27 10:30:58,948 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x02 
1044
2018-08-27 10:30:58,953 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
1045
2018-08-27 10:30:58,954 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
1046
2018-08-27 10:30:58,962 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1047
2018-08-27 10:30:58,962 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
1048
2018-08-27 10:30:58,972 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1049
2018-08-27 10:30:58,974 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB2 0x03 0x04 0xFF 
1050
2018-08-27 10:30:58,981 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6D 0x00 
1051
2018-08-27 10:30:58,981 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xF0 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x65 0x72 0x20 0x31 0x2E 0x30 
1052
2018-08-27 10:30:58,994 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
1053
2018-08-27 10:30:58,994 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x0C 0x0F 0xD2 0x33 0x00 0x00 0x00 0x45 0x73 0x74 0x45 0x49 0x44 0x20 0x76 0x33 0x35 
1054
2018-08-27 10:30:59,005 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
1055
2018-08-27 10:30:59,007 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x04 0x00 0x08 0x27 0x60 0x00 0x12 0x1F 0x00 0x00 0x01 
1056
2018-08-27 10:30:59,018 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x6A 0x82 
1057
2018-08-27 10:30:59,019 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x00 0x0C 0x02 0x3F 0x00 
1058
2018-08-27 10:30:59,030 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1059
2018-08-27 10:30:59,030 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xA4 0x02 0x0C 0x02 0x2F 0x00 
1060
2018-08-27 10:30:59,039 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Receive APDU: 0x90 0x00 
1061
2018-08-27 10:30:59,040 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - Send APDU: 0x00 0xB0 0x00 0x00 0xFF 
1062
2018-08-27 10:30:59,050 [pool-2-thread-14] DEBUG o.o.i.s.wrapper.SingleThreadChannel:-1 - 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 
1063
2018-08-27 10:30:59,059 [pool-2-thread-14] DEBUG org.openecard.event.Recognizer:-1 - Found a recognized card event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
1064
2018-08-27 10:30:59,059 [pool-2-thread-14] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
1065
2018-08-27 10:30:59,059 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_RECOGNIZED
1066
2018-08-27 10:30:59,059 [pool-2-thread-13] INFO  o.o.c.sal.state.SALStateCallback:-1 - Add ConnectionHandle to SAL:
1067
ConnectionHandle:
1068
  Session: -e551M6ztvikAbMknmYPxA
1069
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
1070
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
1071
  CardType: http://bsi.bund.de/cif/npa.xml
1072
2018-08-27 10:30:59,059 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@290d63df
1073
2018-08-27 10:30:59,059 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType$RecognitionInfo@3a116e41
1074
2018-08-27 10:30:59,060 [pool-2-thread-15] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
1075
2018-08-27 10:38:48,089 [pool-2-thread-1] DEBUG org.openecard.event.EventRunner:-1 - Found a card removed event (REINER SCT cyberJack RFID standard (0101290773) 00 00).
1076
2018-08-27 10:38:48,241 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received event.
1077
2018-08-27 10:38:48,241 [pool-2-thread-1] DEBUG o.o.s.p.eac.gui.CardRemovedFilter:-1 - Received CARD_REMOVED event.
1078
2018-08-27 10:38:48,242 [pool-2-thread-1] INFO  o.o.s.p.eac.gui.CardRemovedFilter:-1 - Card removed during processing of EAC GUI.
1079
2018-08-27 10:38:48,291 [pool-2-thread-18] DEBUG org.openecard.richclient.gui.Status:-1 - Event: CARD_REMOVED
1080
2018-08-27 10:38:48,291 [pool-2-thread-18] DEBUG org.openecard.richclient.gui.Status:-1 - ConnectionHandle: iso.std.iso_iec._24727.tech.schema.ConnectionHandleType@1e6286a1
1081
2018-08-27 10:38:48,291 [pool-2-thread-18] DEBUG org.openecard.richclient.gui.Status:-1 - RecognitionInfo: null
1082
2018-08-27 10:38:48,291 [pool-2-thread-18] DEBUG org.openecard.richclient.gui.Status:-1 - IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00
1083
2018-08-27 10:38:48,292 [pool-2-thread-17] INFO  o.o.c.sal.state.SALStateCallback:-1 - Remove ConnectionHandle from SAL.
1084
ConnectionHandle:
1085
  Session: -e551M6ztvikAbMknmYPxA
1086
  ContextHandle: 8367BCB58ABFEDD4B2F706A4D300C9C2
1087
  IFDName: REINER SCT cyberJack RFID standard (0101290773) 00 00  SlotIndex: 0
1088
2018-08-27 10:38:48,409 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Detecting initial terminal status.
1089
2018-08-27 10:38:48,409 [pool-2-thread-1] DEBUG o.o.scio.PCSCTerminals$PCSCWatcher:-1 - Terminal='REINER SCT cyberJack RFID standard (0101290773) 00 00' cardPresent=false
(1-1/4)