Автор: tok
Дата сообщения: 22.05.2013 08:46
victorae
С галкой SASL тоже не подключается
[more=Openfire в отладке]
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Trying to find a user's DN based on their username. sAMAccountName: test, Base DN: DC="gerkules",DC="ad"...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Creating a DirContext in LdapManager.getContext()...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Warning: Using unencrypted connection to LDAP service!
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Created hashtable with context values, attempting to create context...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... context created successfully, returning.
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Starting LDAP search...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... search finished
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: In LdapManager.checkAuthentication(userDN, password), userDN is: CN="������� ������ ����������",OU="������������"...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Warning: Using unencrypted connection to LDAP service!
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Created context values, attempting to create context...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... context created successfully, returning.
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Trying to find a user's DN based on their username. sAMAccountName: test, Base DN: DC="gerkules",DC="ad"...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Creating a DirContext in LdapManager.getContext()...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Warning: Using unencrypted connection to LDAP service!
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Created hashtable with context values, attempting to create context...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... context created successfully, returning.
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Starting LDAP search...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... search finished
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Trying to find a user's DN based on their username. sAMAccountName: test, Base DN: DC="gerkules",DC="ad"...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Creating a DirContext in LdapManager.getContext()...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Warning: Using unencrypted connection to LDAP service!
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Created hashtable with context values, attempting to create context...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... context created successfully, returning.
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Starting LDAP search...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... search finished
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Creating a DirContext in LdapManager.getContext()...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Warning: Using unencrypted connection to LDAP service!
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: Created hashtable with context values, attempting to create context...
2013.05.22 12:39:20 org.jivesoftware.openfire.ldap.LdapManager - LdapManager: ... context created successfully, returning.
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrapHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=0 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NEED_UNWRAP
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=148 cap=1024: 16 03 01 00 8F 01 00 00 8B 03 01 51 9C 5A 0F 68 61 7E B9 67 ED 94 4A 1A 17 4B E3 A8 B3 45 3F D7 E0 B1 C8 0F 99 FF F5 57 B7 85 21 20 51 9C 5A 01 2F 43 B7 C1 47 59 96 23 52 AB 0E EF 96 AF 47 19 BA C0 B0 16 7D DE 57 46 01 10 54 85 00 18 00 2F 00 35 00 05 00 0A C0 13 C0 14 C0 09 C0 0A 00 32 00 38 00 13 00 04 01 00 00 2A FF 01 00 01 00 00 00 00 0D 00 0B 00 00 08 73 79 73 61 64 6D 69 6E 00 0A 00 06 00 04 00 17 00 18 00 0B 00 02 01 00 00 23 00 00])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrapHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=148 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NEED_TASK
bytesConsumed = 148 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_TASK
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTasks()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTask: com.sun.net.ssl.internal.ssl.Handshaker$DelegatedTask@79d7b3
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTasks(): NEED_WRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_WRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NEED_UNWRAP
bytesConsumed = 0 bytesProduced = 816
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] write outNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=816 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] session write: HeapBuffer[pos=0 lim=816 cap=816: 16 03 01 03 2B 02 00 00 46 03 01 51 9C 5A 0F 81 BB 7B DF 3E CF D1 F2 1E 89 9D 1E 79 73 7D 80 BD F1 70 F2 38 B7 01 55 66 A2 E9 AC 20 51 9C 5A 0F BE 29 6B C9 81 72 94 88 23 8F 95 7D 84 EA F6 62 22 CA 44 E0 1E C8 8E DE 8B E2 21 50 00 2F 00 0B 00 02 D9 00 02 D6 00 02 D3 30 82 02 CF 30 82 01 B7 A0 03 02 01 02 02 08 02 B8 4D 69 29 C4 40 5F 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05 05 00 30 13 31 11 30 0F 06 03 55 04 03 0C 08 73 79 73 61 64 6D 69 6E 30 1E 17 0D 31 33 30 33 32 37 30 34 33 32 33 35 5A 17 0D 31 38 30 33 30 31 30 34 33 32 33 35 5A 30 13 31 11 30 0F 06 03 55 04 03 0C 08 73 79 73 61 64 6D 69 6E 30 82 01 22 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01 05 00 03 82 01 0F 00 30 82 01 0A 02 82 01 01 00 81 D4 F8 52 2A 56 40 7B A6 14 63 B8 E1 81 4C F1 44 1E 52 DA 90 53 2E 53 75 8D D3 9A 5D 54 9A 7D AF 5E 92 47 80 45 6D F7 9D 50 07 D5 4D 5F CD 1B 8E FB 99 4E 56 E0 14 73 59 47 15 85 35 59 5E 7F 15 38 BA DA F8 0E 81 C6 0F E0 DC 68 13 EF 3B BA A5 44 06 BE 8B 41 97 F0 D9 14 4A F3 EC BA 56 49 A1 8B 3F A8 F1 90 34 D2 D8 E7 D5 D3 41 6F 4B E1 3B 7A 1E DE 1F 87 B7 C8 57 21 45 CC 74 DA 6D B9 CE 24 25 C1 84 6E AA 56 FD A2 74 E6 47 FA 66 CC 45 D6 58 E6 09 0D 46 92 AA 7F 18 96 F4 96 17 D7 4C 51 15 A4 32 79 A6 57 82 43 AC BE B5 BD 9C B1 D3 26 E0 17 9A 3D 35 CD 3E 5B 04 4D 82 B2 38 0C 9B 87 71 B9 8E CE 59 67 3C AF 2D 6F D4 25 B0 E4 53 03 CC FD F6 49 A4 2E 8A BE 58 9E B9 39 0F 77 84 79 FB 96 37 C4 50 6F 57 20 33 06 C1 FD B1 60 91 BC 10 28 0B D4 F1 05 E9 A6 66 53 EB EA D6 15 02 03 01 00 01 A3 27 30 25 30 23 06 03 55 1D 11 04 1C 30 1A A0 18 06 08 2B 06 01 05 05 07 08 05 A0 0C 0C 0A 2A 2E 73 79 73 61 64 6D 69 6E 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05 05 00 03 82 01 01 00 22 1A B1 7D E2 EF 0B 67 14 33 F5 C6 1E 40 87 2C CA 44 CD AD 6F 30 11 AA 57 0C C4 B3 BC CC 1F 02 21 28 4F 93 F0 ED B1 71 58 50 B8 D3 3A 2C 1C 51 43 40 7C 58 26 94 CB A6 57 C0 3E EE D3 E9 00 C6 1B 8C 0F 36 99 43 BC 99 47 0C 34 A6 E5 1D 66 F1 68 DD 12 0F B0 53 D4 C7 09 1D E8 4C CC 88 B8 53 5C 24 66 24 41 20 A7 CF BA DD 21 AE 0E EA E7 98 41 C2 62 69 3C 8C 08 F9 31 DE 4D 66 1A 38 2E A1 62 76 36 E6 D9 9E 1A BA 6F 01 89 40 FD F2 83 97 8C 3B 81 E2 FF C5 0D E0 CB 69 4C 7B DC DA BE 1E CF B3 FB 74 03 B9 D9 65 EC 9F E4 86 BB 42 EB DE 9C 72 11 A3 C7 6E CB 4A 2E 2C A0 5D 2D 77 F2 B3 B3 BD 01 AC 44 F8 DA 2F AD 2F 73 76 55 93 81 C1 72 DC 44 18 7C 02 93 08 57 55 B4 29 F3 40 67 77 8B BB C6 AE 24 A1 68 52 82 3A F8 CB B6 97 52 F3 CB 32 21 58 AF 55 2F CE 0D 7E 6A 23 B8 D7 00 7E 0E 00 00 00]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] already encrypted: HeapBuffer[pos=0 lim=816 cap=816: 16 03 01 03 2B 02 00 00 46 03 01 51 9C 5A 0F 81 BB 7B DF 3E CF D1 F2 1E 89 9D 1E 79 73 7D 80 BD F1 70 F2 38 B7 01 55 66 A2 E9 AC 20 51 9C 5A 0F BE 29 6B C9 81 72 94 88 23 8F 95 7D 84 EA F6 62 22 CA 44 E0 1E C8 8E DE 8B E2 21 50 00 2F 00 0B 00 02 D9 00 02 D6 00 02 D3 30 82 02 CF 30 82 01 B7 A0 03 02 01 02 02 08 02 B8 4D 69 29 C4 40 5F 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05 05 00 30 13 31 11 30 0F 06 03 55 04 03 0C 08 73 79 73 61 64 6D 69 6E 30 1E 17 0D 31 33 30 33 32 37 30 34 33 32 33 35 5A 17 0D 31 38 30 33 30 31 30 34 33 32 33 35 5A 30 13 31 11 30 0F 06 03 55 04 03 0C 08 73 79 73 61 64 6D 69 6E 30 82 01 22 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01 05 00 03 82 01 0F 00 30 82 01 0A 02 82 01 01 00 81 D4 F8 52 2A 56 40 7B A6 14 63 B8 E1 81 4C F1 44 1E 52 DA 90 53 2E 53 75 8D D3 9A 5D 54 9A 7D AF 5E 92 47 80 45 6D F7 9D 50 07 D5 4D 5F CD 1B 8E FB 99 4E 56 E0 14 73 59 47 15 85 35 59 5E 7F 15 38 BA DA F8 0E 81 C6 0F E0 DC 68 13 EF 3B BA A5 44 06 BE 8B 41 97 F0 D9 14 4A F3 EC BA 56 49 A1 8B 3F A8 F1 90 34 D2 D8 E7 D5 D3 41 6F 4B E1 3B 7A 1E DE 1F 87 B7 C8 57 21 45 CC 74 DA 6D B9 CE 24 25 C1 84 6E AA 56 FD A2 74 E6 47 FA 66 CC 45 D6 58 E6 09 0D 46 92 AA 7F 18 96 F4 96 17 D7 4C 51 15 A4 32 79 A6 57 82 43 AC BE B5 BD 9C B1 D3 26 E0 17 9A 3D 35 CD 3E 5B 04 4D 82 B2 38 0C 9B 87 71 B9 8E CE 59 67 3C AF 2D 6F D4 25 B0 E4 53 03 CC FD F6 49 A4 2E 8A BE 58 9E B9 39 0F 77 84 79 FB 96 37 C4 50 6F 57 20 33 06 C1 FD B1 60 91 BC 10 28 0B D4 F1 05 E9 A6 66 53 EB EA D6 15 02 03 01 00 01 A3 27 30 25 30 23 06 03 55 1D 11 04 1C 30 1A A0 18 06 08 2B 06 01 05 05 07 08 05 A0 0C 0C 0A 2A 2E 73 79 73 61 64 6D 69 6E 30 0D 06 09 2A 86 48 86 F7 0D 01 01 05 05 00 03 82 01 01 00 22 1A B1 7D E2 EF 0B 67 14 33 F5 C6 1E 40 87 2C CA 44 CD AD 6F 30 11 AA 57 0C C4 B3 BC CC 1F 02 21 28 4F 93 F0 ED B1 71 58 50 B8 D3 3A 2C 1C 51 43 40 7C 58 26 94 CB A6 57 C0 3E EE D3 E9 00 C6 1B 8C 0F 36 99 43 BC 99 47 0C 34 A6 E5 1D 66 F1 68 DD 12 0F B0 53 D4 C7 09 1D E8 4C CC 88 B8 53 5C 24 66 24 41 20 A7 CF BA DD 21 AE 0E EA E7 98 41 C2 62 69 3C 8C 08 F9 31 DE 4D 66 1A 38 2E A1 62 76 36 E6 D9 9E 1A BA 6F 01 89 40 FD F2 83 97 8C 3B 81 E2 FF C5 0D E0 CB 69 4C 7B DC DA BE 1E CF B3 FB 74 03 B9 D9 65 EC 9F E4 86 BB 42 EB DE 9C 72 11 A3 C7 6E CB 4A 2E 2C A0 5D 2D 77 F2 B3 B3 BD 01 AC 44 F8 DA 2F AD 2F 73 76 55 93 81 C1 72 DC 44 18 7C 02 93 08 57 55 B4 29 F3 40 67 77 8B BB C6 AE 24 A1 68 52 82 3A F8 CB B6 97 52 F3 CB 32 21 58 AF 55 2F CE 0D 7E 6A 23 B8 D7 00 7E 0E 00 00 00]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrapHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=0 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NEED_UNWRAP
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=326 cap=1024: 16 03 01 01 06 10 00 01 02 01 00 46 48 D8 64 B5 CC 51 BC D5 8D 6E B3 B6 9B CB 5A 46 DE 3B AF 14 87 85 AF BA F6 78 92 9B 79 69 72 24 23 D8 C4 F7 C2 6D F5 34 9B DE 8F 3B 85 1D 8E 47 E4 6E 80 97 5B 00 D6 37 7A 0E 90 03 FF 7E F4 CE 22 0A E8 92 75 65 2A F0 24 A2 99 87 FA E5 1C 2F 6F 07 24 7D 22 31 65 F0 9E D2 12 AD 20 8D 1D B6 90 6D 16 58 7E 40 0A BF DC A5 A5 33 D1 95 C1 C3 C3 9C FA 63 E4 90 07 0E B9 2D 0B 77 5E 0B 5F 33 36 90 6C 02 FB 43 C2 B6 7A 0B 9B A1 CE 6A 94 37 CC 56 32 7D E3 1D A0 84 19 BC 07 5E 4E E4 01 8B 78 9F 1B 85 70 DB 06 45 4C 0B 7A 6C 1B BA 51 64 B1 61 24 99 09 3D A1 74 64 A7 5C 2C 29 CE 0C C7 D5 EC 48 8B 7F D0 59 BD D3 0A F4 B0 E3 20 CE AC B5 3E 85 CA 94 2B 36 F1 8E C9 C7 02 A5 52 59 CC 12 14 07 15 02 6F E0 25 27 9B D1 B1 7A 8E 4A D3 7E 43 45 65 E3 AC 7B 0D 22 C3 24 02 F6 CA B2 14 03 01 00 01 01 16 03 01 00 30 B3 44 05 EE EB 22 5A DF 79 44 F5 93 05 0D 84 83 D7 27 BA B9 4C 6B 71 9B B8 38 E3 AE AF 25 C0 FC 91 93 3A EB 81 B5 5F 39 52 2A 66 0B B8 66 B6 48])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrapHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=326 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NEED_TASK
bytesConsumed = 267 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_TASK
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTasks()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTask: com.sun.net.ssl.internal.ssl.Handshaker$DelegatedTask@13b2f86
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doTasks(): NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_UNWRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrapHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=59 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NEED_UNWRAP
bytesConsumed = 6 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=6 lim=59 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NEED_WRAP
bytesConsumed = 53 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_WRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NEED_WRAP
bytesConsumed = 0 bytesProduced = 6
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] write outNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=6 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] session write: HeapBuffer[pos=0 lim=6 cap=6: 14 03 01 00 01 01]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] already encrypted: HeapBuffer[pos=0 lim=6 cap=6: 14 03 01 00 01 01]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] doHandshake()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=NEED_WRAP
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = FINISHED
bytesConsumed = 0 bytesProduced = 53
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] write outNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=53 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] session write: HeapBuffer[pos=0 lim=53 cap=53: 16 03 01 00 30 DF D0 A2 F0 3C 62 5B A4 4D 5E 93 A7 07 17 E5 55 D2 61 BB 58 2C D5 D5 51 04 39 70 FE CF DA 6B 14 A2 10 27 7D AE C4 A5 99 49 F6 6E 32 46 A0 BF 74]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] already encrypted: HeapBuffer[pos=0 lim=53 cap=53: 16 03 01 00 30 DF D0 A2 F0 3C 62 5B A4 4D 5E 93 A7 07 17 E5 55 D2 61 BB 58 2C D5 D5 51 04 39 70 FE CF DA 6B 14 A2 10 27 7D AE C4 A5 99 49 F6 6E 32 46 A0 BF 74]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=FINISHED
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] sslSession CipherSuite used TLS_RSA_WITH_AES_128_CBC_SHA
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] handshakeStatus=FINISHED
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] sslSession CipherSuite used TLS_RSA_WITH_AES_128_CBC_SHA
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=181 cap=512: 17 03 01 00 B0 A3 EC F4 B6 26 CD 5B 10 17 76 BC 5D 9C D2 FF 2D 8D 00 E2 5A 6D D6 3D CC 4D 9E E4 EA 57 83 28 8C 38 0B 90 64 30 CF 5D D8 03 9A A7 61 B6 C3 5A 42 D6 23 01 4A 59 39 C8 76 C6 0C 46 65 F2 84 22 D8 34 BF C8 B9 6E 61 F4 36 36 07 C6 D9 71 3F AA 15 DF BF 62 01 D5 D5 7E B9 2E 17 5C F3 45 F2 7E F2 A5 8A EC 2B 23 54 78 A1 11 92 86 B6 24 97 4D 99 48 E2 AF 74 87 7A 50 42 5F 49 B8 86 95 05 08 58 99 02 22 5B F2 09 0E B4 06 5F BE D1 BE 17 90 73 A9 3A FF EA 0A E8 BD DE EC 7B E8 CF 5E 80 67 E8 FA 11 2E 33 6B B6 97 CA 3B 42 19 63 C6 6C 7F E6])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrap()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=181 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 181 bytesProduced = 151
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=181 lim=181 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=151 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=151 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] app data read: HeapBuffer[pos=0 lim=151 cap=151: 3C 3F 78 6D 6C 20 76 65 72 73 69 6F 6E 3D 22 31 2E 30 22 20 65 6E 63 6F 64 69 6E 67 3D 22 55 54 46 2D 38 22 3F 3E 3C 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 63 6C 69 65 6E 74 22 20 74 6F 3D 22 73 79 73 61 64 6D 69 6E 22 20 78 6D 6C 6E 73 3A 73 74 72 65 61 6D 3D 22 68 74 74 70 3A 2F 2F 65 74 68 65 72 78 2E 6A 61 62 62 65 72 2E 6F 72 67 2F 73 74 72 65 61 6D 73 22 20 78 6D 6C 3A 6C 61 6E 67 3D 22 65 6E 22 3E] (3C 3F 78 6D 6C 20 76 65 72 73 69 6F 6E 3D 22 31 2E 30 22 20 65 6E 63 6F 64 69 6E 67 3D 22 55 54 46 2D 38 22 3F 3E 3C 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 63 6C 69 65 6E 74 22 20 74 6F 3D 22 73 79 73 61 64 6D 69 6E 22 20 78 6D 6C 6E 73 3A 73 74 72 65 61 6D 3D 22 68 74 74 70 3A 2F 2F 65 74 68 65 72 78 2E 6A 61 62 62 65 72 2E 6F 72 67 2F 73 74 72 65 61 6D 73 22 20 78 6D 6C 3A 6C 61 6E 67 3D 22 65 6E 22 3E)
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypt: HeapBuffer[pos=0 lim=167 cap=167: 3C 3F 78 6D 6C 20 76 65 72 73 69 6F 6E 3D 27 31 2E 30 27 20 65 6E 63 6F 64 69 6E 67 3D 27 55 54 46 2D 38 27 3F 3E 3C 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 20 78 6D 6C 6E 73 3A 73 74 72 65 61 6D 3D 22 68 74 74 70 3A 2F 2F 65 74 68 65 72 78 2E 6A 61 62 62 65 72 2E 6F 72 67 2F 73 74 72 65 61 6D 73 22 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 63 6C 69 65 6E 74 22 20 66 72 6F 6D 3D 22 73 79 73 61 64 6D 69 6E 22 20 69 64 3D 22 64 64 61 31 65 34 36 35 22 20 78 6D 6C 3A 6C 61 6E 67 3D 22 65 6E 22 3E]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 167 bytesProduced = 197
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypted buf: HeapBuffer[pos=0 lim=197 cap=197: 17 03 01 00 C0 E6 08 AC A4 C6 DD 6B 8C 8C 12 76 66 A4 29 4C 5A B9 5D 2F DA C0 C4 EA 21 45 87 CB 17 36 93 F7 2E 68 7D 04 89 06 FF C9 3B 35 12 21 83 7E 6D CC AE 1D 64 8D 5A 46 7B 17 AD 56 E1 DC 10 2A 22 45 54 4E 13 5A 46 B3 5E 52 C9 D3 0A 45 E9 B1 64 3A BD 5A 0C 64 CF 13 99 7C FE 5B FF 3F A7 0F 02 7C C4 7F 0E FA 3B 8F F0 D3 D1 D3 74 D3 24 99 0F 0A 3B 57 64 0E F7 E4 4B C7 D8 81 97 82 18 99 4E 51 EF 7F 98 17 51 83 11 D4 E2 D2 48 94 55 8D 11 FB F3 75 61 F8 7C CE A6 DE 63 48 32 6D 91 A1 F5 2E 52 FD 43 22 7A 53 12 E5 63 A0 34 31 EB 55 DE 20 B0 B1 3A 7A 4C AA 61 B1 22 CE A2 87 D0 DE 26 3C 1A]
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=133 cap=512: 17 03 01 00 80 8E 71 FA 33 B0 17 D6 E0 3F 61 A7 34 F6 2F AC 20 7B 7C 35 84 83 39 8B 8F FF B2 70 16 98 AF FC BC 43 7D A6 75 0F 28 41 80 58 91 1C 32 6A 01 7A 80 2B 2D 74 97 D5 E2 D1 44 6B 5E 88 44 86 A1 C7 03 36 2D A1 A0 B7 5A 9C B5 6C 24 DB 56 91 3D 41 E8 05 E9 11 D5 6A 46 94 53 54 03 A3 95 E4 AD F7 5A FE DE B0 D6 00 11 44 62 BA 34 9A 86 74 DC CB 7D 49 30 51 DB CC 67 DD EF 79 E9 5A E6 C9 E7 8D AC])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrap()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=133 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 133 bytesProduced = 100
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=133 lim=133 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=100 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=100 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] app data read: HeapBuffer[pos=0 lim=100 cap=100: 3C 69 71 20 74 79 70 65 3D 22 67 65 74 22 20 69 64 3D 22 6D 69 72 5F 32 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E] (3C 69 71 20 74 79 70 65 3D 22 67 65 74 22 20 69 64 3D 22 6D 69 72 5F 32 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E)
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypt: HeapBuffer[pos=0 lim=125 cap=4096: 3C 69 71 20 74 79 70 65 3D 22 72 65 73 75 6C 74 22 20 69 64 3D 22 6D 69 72 5F 32 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 70 61 73 73 77 6F 72 64 2F 3E 3C 72 65 73 6F 75 72 63 65 2F 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 125 bytesProduced = 165
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypted buf: HeapBuffer[pos=0 lim=165 cap=165: 17 03 01 00 A0 45 2C 78 4B 57 7C FC E0 22 AF 17 FE 15 16 E7 C9 82 0C 64 73 A7 68 D4 1B 80 7F F1 4A C8 A9 42 32 3B CC 54 60 FD 40 5B BF 53 EF 93 F1 A0 3B AE 41 6A B4 DD 73 39 BF FC 72 3F B5 24 D6 1F 73 EB 72 34 99 BB 16 01 76 D2 B8 EB D5 33 55 23 6D 53 EC 44 7A E0 1C E9 C7 A0 E7 7F CB FE DF 82 21 47 46 CC 08 D7 BA 39 E7 49 40 F7 1F 32 40 63 2C 44 1C DB E1 0C 9A DC 12 41 3F F4 11 87 36 5F 19 C4 A8 3A 4A C2 D6 06 FE A2 07 37 59 BF A3 CE B4 57 D1 74 A8 62 AF 7E ED 09 4A CB CC 7A 14 DE C2 08 73]
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=181 cap=256: 17 03 01 00 B0 59 EE 9D 4F BF 7E B3 BF 62 28 D3 D5 A6 01 C5 6D 74 98 30 85 F5 FF 57 24 85 E5 32 4D F0 8C 58 6D DF F9 4F 51 08 9A B0 CF 75 BE C0 D7 2D 7D 93 3D E3 AB 4D CE 21 A1 88 82 57 6E A1 E9 80 DF 0D CC CB 3D 5A FD DC D4 DE 6C A6 59 9F 2F 6C 4D B6 27 4D B6 12 86 7D 91 A5 61 12 B3 3B B9 A0 FE E1 F4 86 2D 73 91 C1 B0 2D 2A FF CB 31 6F 77 3B 92 C6 31 C4 F0 1E D7 EA 49 FE 2F 19 93 7E F6 3C 19 78 82 7D B3 82 4F 8E E5 28 EC 0B F9 06 BF AC DD 7A 50 3F 9B 0D 5F 7D 6B 00 71 49 D8 09 9E EF 7B DC C7 BD 7B 71 01 93 49 D2 C7 ED BA BF C1 AF A3 AC])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrap()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=181 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 181 bytesProduced = 150
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=181 lim=181 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=150 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=150 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] app data read: HeapBuffer[pos=0 lim=150 cap=150: 3C 69 71 20 74 79 70 65 3D 22 73 65 74 22 20 69 64 3D 22 6D 69 72 5F 33 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 70 61 73 73 77 6F 72 64 3E 3C 2F 70 61 73 73 77 6F 72 64 3E 3C 72 65 73 6F 75 72 63 65 3E 73 79 73 61 64 6D 69 6E 3C 2F 72 65 73 6F 75 72 63 65 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E] (3C 69 71 20 74 79 70 65 3D 22 73 65 74 22 20 69 64 3D 22 6D 69 72 5F 33 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 70 61 73 73 77 6F 72 64 3E 3C 2F 70 61 73 73 77 6F 72 64 3E 3C 72 65 73 6F 75 72 63 65 3E 73 79 73 61 64 6D 69 6E 3C 2F 72 65 73 6F 75 72 63 65 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E)
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypt: HeapBuffer[pos=0 lim=264 cap=4096: 3C 69 71 20 74 79 70 65 3D 22 65 72 72 6F 72 22 20 69 64 3D 22 6D 69 72 5F 33 22 20 74 6F 3D 22 73 79 73 61 64 6D 69 6E 2F 64 64 61 31 65 34 36 35 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 64 75 72 61 6E 69 6E 5F 61 76 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 70 61 73 73 77 6F 72 64 2F 3E 3C 72 65 73 6F 75 72 63 65 3E 73 79 73 61 64 6D 69 6E 3C 2F 72 65 73 6F 75 72 63 65 3E 3C 2F 71 75 65 72 79 3E 3C 65 72 72 6F 72 20 63 6F 64 65 3D 22 34 30 31 22 20 74 79 70 65 3D 22 61 75 74 68 22 3E 3C 6E 6F 74 2D 61 75 74 68 6F 72 69 7A 65 64 20 78 6D 6C 6E 73 3D 22 75 72 6E 3A 69 65 74 66 3A 70 61 72 61 6D 73 3A 78 6D 6C 3A 6E 73 3A 78 6D 70 70 2D 73 74 61 6E 7A 61 73 22 2F 3E 3C 2F 65 72 72 6F 72 3E 3C 2F 69 71 3E]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 264 bytesProduced = 293
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypted buf: HeapBuffer[pos=0 lim=293 cap=293: 17 03 01 01 20 E0 56 82 84 5B 0D 9C 57 E3 3A 99 0A 8C 9E 26 5C BB 23 85 4C C2 09 6B 0A C0 97 7E E8 26 46 DF 4E 5F D5 53 62 52 9B A7 E0 51 C9 78 0C CD 0F 9E 9C F7 EA 03 4B CF 6B 01 AB 2C 19 5B 8F B5 21 B9 FA 05 93 8F C6 37 DD 6F F8 55 7A D7 D3 E8 44 70 BF DB 63 A9 44 56 CF 16 BA D3 55 7F 5B 10 6A 27 F3 81 3C ED D7 96 04 A2 E5 17 EE 18 0B 15 86 58 2E A7 52 A2 81 2A 87 52 E6 BD FD 20 94 39 73 C8 F7 37 13 87 0F 36 1E 89 C6 88 BD F6 5C 30 17 7E 54 EA 7D 36 BE CD 9F C6 80 A8 42 DE B1 D8 7C 50 5C 02 3B D7 26 FE FD C9 B9 AA D8 01 88 18 92 B2 4B 6C 94 64 76 1C 34 44 52 95 EB FC 3B 3B F5 12 B3 D7 5A CA 5C CD B7 8D 5E CA 6C 96 77 41 56 9D EE A7 3C 7A 57 C4 35 63 E8 E4 A0 6D 5B 97 47 D5 D0 8D 82 C7 D9 07 44 D0 FE 68 EF C8 CE 7C F2 AB 61 98 7B C7 23 67 39 EB 1D 0C F6 FE 6E D9 45 A4 5C 6C 40 14 FC CD 62 D9 8A 08 A8 12 8F B2 87 59 CD DB 5B 75 07 AA 15 8C 6C 55 06 B2 33 0E 4E F4 A1]
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Data Read: org.apache.mina.filter.support.SSLHandler@5be8f4 (HeapBuffer[pos=0 lim=53 cap=256: 17 03 01 00 30 E1 B2 06 AF 73 46 1C EC AF 21 DD 8C 3A 87 34 8C 9D 97 79 76 0C 7F 59 3B 93 DC 9C FD DC DB 8A 36 35 FD 33 00 28 45 AE E8 68 40 C6 7E C6 59 C4 CE])
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] unwrap()
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=53 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 53 bytesProduced = 16
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] inNetBuffer: java.nio.DirectByteBuffer[pos=53 lim=53 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=16 lim=33330 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 0 bytesProduced = 0
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=16 cap=33330]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] app data read: HeapBuffer[pos=0 lim=16 cap=16: 3C 2F 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 3E] (3C 2F 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 3E)
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Filtered Write: org.apache.mina.filter.support.SSLHandler@5be8f4
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypt: HeapBuffer[pos=0 lim=16 cap=16: 3C 2F 73 74 72 65 61 6D 3A 73 74 72 65 61 6D 3E]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Wrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING
bytesConsumed = 16 bytesProduced = 53
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] encrypted buf: HeapBuffer[pos=0 lim=53 cap=53: 17 03 01 00 30 0A A1 96 5B 45 FF C3 AB 9C 6F B8 BC 5F 91 AA 58 12 A9 34 F1 86 62 DE D3 C8 84 1C BA 0F 9F 94 B6 B3 91 65 0B 98 B8 41 FB E3 CB CF 32 C4 4A 31 75]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] write outNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=37 cap=16665]
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] session write: HeapBuffer[pos=0 lim=37 cap=37: 15 03 01 00 20 E0 57 73 44 78 35 B3 E1 0F C2 BB C2 34 70 8C DC F5 CB 14 CA 62 9D BC 59 5C 46 70 91 1B EB 85 7E]
2013.05.22 12:39:27 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /192.168.100.18:59423
2013.05.22 12:39:27 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/192.168.100.18:59423] Unexpected exception from SSLEngine.closeInbound().
javax.net.ssl.SSLException: Inbound closed before receiving peer's close_notify: possible truncation attack?
at com.sun.net.ssl.internal.ssl.Alerts.getSSLException(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLEngineImpl.fatal(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLEngineImpl.fatal(Unknown Source)
at com.sun.net.ssl.internal.ssl.SSLEngineImpl.closeInbound(Unknown Source)
at org.apache.mina.filter.support.SSLHandler.destroy(SSLHandler.java:167)
at org.apache.mina.filter.SSLFilter.sessionClosed(SSLFilter.java:367)
at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:269)
at org.apache.mina.common.support.AbstractIoFilterChain.access$800(AbstractIoFilterChain.java:53)
at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.sessionClosed(AbstractIoFilterChain.java:633)
at org.apache.mina.common.support.AbstractIoFilterChain$HeadFilter.sessionClosed(AbstractIoFilterChain.java:484)
at org.apache.mina.common.support.AbstractIoFilterChain.callNextSessionClosed(AbstractIoFilterChain.java:269)
at org.apache.mina.common.support.AbstractIoFilterChain.fireSessionClosed(AbstractIoFilterChain.java:264)
at org.apache.mina.common.support.IoServiceListenerSupport.fireSessionDestroyed(IoServiceListenerSupport.java:224)
at org.apache.mina.transport.socket.nio.SocketIoProcessor.doRemove(SocketIoProcessor.java:188)
at org.apache.mina.transport.socket.nio.SocketIoProcessor.access$600(SocketIoProcessor.java:45)
at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProcessor.java:489)
at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
at java.lang.Thread.run(Unknown Source) [/more]
[more=Miranda пишет:]
[12:43:28 (NULL)] KeepStatus: assigning status 40072 to JABBER_1
[12:43:28 JABBER_1] SetAwayMsg called, wParam=40072 lParam=
[12:43:28 JABBER_1] PS_SETSTATUS( 40072 )
[12:43:28 JABBER_1] Thread started: type=0
[12:43:28 JABBER_1] SetAwayMsg called, wParam=40072 lParam=
[12:43:28 JABBER_1] _xmpp-client._tcp.sysadmin not resolved
[12:43:28 (NULL)] KeepStatus: assigning status 40072 to JABBER_1
[12:43:28 JABBER_1] Connection request to sysadmin:5222 (Flags 0)....
[12:43:28 JABBER_1] (02C83240) Connecting to server sysadmin:5222....
[12:43:28 JABBER_1] (1304) Connected to sysadmin:5222
[12:43:28 JABBER_1] Thread type=0 server='sysadmin' port='5222'
[12:43:28 JABBER_1] Local IP = 192.168.100.18
[12:43:28 JABBER_1] Stream is initializing after connect
[12:43:28 JABBER_1] (02C83240:1304) Data sent
<?xml version="1.0" encoding="UTF-8"?><stream:stream xmlns="jabber:client" to="sysadmin" xmlns:stream="http://etherx.jabber.org/streams" xml:lang="en">
[12:43:28 JABBER_1] Entering main recv loop
[12:43:28 JABBER_1] (02C83240:1304) Data received
<?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="sysadmin" id="c0bcd85" xml:lang="en">
[12:43:28 JABBER_1] recvResult = 166
[12:43:28 JABBER_1] bytesParsed = 166
[12:43:28 JABBER_1] IqAdd id=12, proc=0, func=0x1263dbee
[12:43:28 JABBER_1] (02C83240:1304) Data sent
<iq type="get" id="mir_12"><query xmlns="jabber:iq:auth"><username>test</username></query></iq>
[12:43:28 JABBER_1] (02C83240:1304) Data received
<iq type="result" id="mir_12"><query xmlns="jabber:iq:auth"><username>test</username><password/><resource/></query></iq>
[12:43:28 JABBER_1] recvResult = 126
[12:43:28 JABBER_1] bytesParsed = 126
[12:43:28 JABBER_1] Handling iq request for id=12
[12:43:28 JABBER_1] <iq/> iqIdGetAuth
[12:43:28 JABBER_1] IqAdd id=13, proc=0, func=0x1263deb7
[12:43:28 JABBER_1] (02C83240:1304) Data sent
<iq type="set" id="mir_13"><query xmlns="jabber:iq:auth"><username>test</username><password></password><resource>sysadmin</resource></query></iq>
[12:43:28 JABBER_1] (02C83240:1304) Data received
<iq type="error" id="mir_13" to="sysadmin/c0bcd85"><query xmlns="jabber:iq:auth"><username>test</username><password/><resource>sysadmin</resource></query><error code="401" type="auth"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>
[12:43:28 JABBER_1] recvResult = 264
[12:43:28 JABBER_1] bytesParsed = 264
[12:43:28 JABBER_1] Handling iq request for id=13
[12:43:28 JABBER_1] <iq/> iqIdSetAuth
[12:43:28 JABBER_1] (02C83240:1304) Data sent
</stream:stream>
[12:43:28 JABBER_1] (02C83240:1304) Data received
</stream:stream>
[12:43:28 JABBER_1] recvResult = 16
[12:43:28 JABBER_1] bytesParsed = 0
[12:43:28 JABBER_1] Unknown state: bytesParsed=0, datalen=16, jabberNetworkBufferSize=2048
[12:43:28 JABBER_1] Connection closed gracefully
[12:43:28 JABBER_1] recvResult = 0
[12:43:28 JABBER_1] Thread ended: type=0 server='sysadmin'
[12:43:28 JABBER_1] (02C83240:1304) Connection closed internal
[12:43:28 JABBER_1] (02C83240:4294967295) Connection closed
[12:43:28 JABBER_1] Exiting ServerThread
[12:43:34 JABBER_1] SetAwayMsg called, wParam=40072 lParam=
[12:44:28 (NULL)] KeepStatus: assigning status 40072 to JABBER_1
[12:44:28 JABBER_1] SetAwayMsg called, wParam=40072 lParam=
[12:44:28 JABBER_1] PS_SETSTATUS( 40072 )
[12:44:28 JABBER_1] Thread started: type=0
[12:44:28 JABBER_1] SetAwayMsg called, wParam=40072 lParam=
[12:44:28 JABBER_1] _xmpp-client._tcp.sysadmin not resolved
[12:44:28 (NULL)] KeepStatus: assigning status 40072 to JABBER_1
[12:44:28 JABBER_1] Connection request to sysadmin:5222 (Flags 0)....
[12:44:28 JABBER_1] (02C83240) Connecting to server sysadmin:5222....
[12:44:28 JABBER_1] (972) Connected to sysadmin:5222
[12:44:28 JABBER_1] Thread type=0 server='sysadmin' port='5222'
[12:44:28 JABBER_1] Local IP = 192.168.100.18
[12:44:28 JABBER_1] Stream is initializing after connect
[12:44:28 JABBER_1] (02C83240:972) Data sent
<?xml version="1.0" encoding="UTF-8"?><stream:stream xmlns="jabber:client" to="sysadmin" xmlns:stream="http://etherx.jabber.org/streams" xml:lang="en">
[12:44:28 JABBER_1] Entering main recv loop
[12:44:28 JABBER_1] (02C83240:972) Data received
<?xml version='1.0' encoding='UTF-8'?><stream:stream xmlns:stream="http://etherx.jabber.org/streams" xmlns="jabber:client" from="sysadmin" id="c8acfa44" xml:lang="en">
[12:44:28 JABBER_1] recvResult = 167
[12:44:28 JABBER_1] bytesParsed = 167
[12:44:28 JABBER_1] IqAdd id=14, proc=0, func=0x1263dbee
[12:44:28 JABBER_1] (02C83240:972) Data sent
<iq type="get" id="mir_14"><query xmlns="jabber:iq:auth"><username>test</username></query></iq>
[12:44:28 JABBER_1] (02C83240:972) Data received
<iq type="result" id="mir_14"><query xmlns="jabber:iq:auth"><username>test</username><password/><resource/></query></iq>
[12:44:28 JABBER_1] recvResult = 126
[12:44:28 JABBER_1] bytesParsed = 126
[12:44:28 JABBER_1] Handling iq request for id=14
[12:44:28 JABBER_1] <iq/> iqIdGetAuth
[12:44:28 JABBER_1] IqAdd id=15, proc=0, func=0x1263deb7
[12:44:28 JABBER_1] (02C83240:972) Data sent
<iq type="set" id="mir_15"><query xmlns="jabber:iq:auth"><username>test</username><password></password><resource>sysadmin</resource></query></iq>
[12:44:28 JABBER_1] (02C83240:972) Data received
<iq type="error" id="mir_15" to="sysadmin/c8acfa44"><query xmlns="jabber:iq:auth"><username>test</username><password/><resource>sysadmin</resource></query><error code="401" type="auth"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>
[12:44:28 JABBER_1] recvResult = 265
[12:44:28 JABBER_1] bytesParsed = 265
[12:44:28 JABBER_1] Handling iq request for id=15
[12:44:28 JABBER_1] <iq/> iqIdSetAuth
[12:44:28 JABBER_1] (02C83240:972) Data sent
</stream:stream>
[12:44:28 JABBER_1] (02C83240:972) Data received
</stream:stream>
[12:44:28 JABBER_1] recvResult = 16
[12:44:28 JABBER_1] bytesParsed = 0
[12:44:28 JABBER_1] Unknown state: bytesParsed=0, datalen=16, jabberNetworkBufferSize=2048
[12:44:28 JABBER_1] Connection closed gracefully
[12:44:28 JABBER_1] recvResult = 0
[12:44:28 JABBER_1] Thread ended: type=0 server='sysadmin'
[12:44:28 JABBER_1] (02C83240:972) Connection closed internal
[12:44:28 JABBER_1] (02C83240:4294967295) Connection closed [/more]