2017-04-10 Smack - jabberd2 SSL handshake exception on reconnection

----------- First connection is ok ---------------

2017-04-10_19-51-42 D/org.jivesoftware.smack.util.DNSUtil﹕ Resolved SRV RR for _xmpp-client._tcp.xabber.modum.by: jabber.modum.by:5220 prio:5:w:0
2017-04-10_19-51-42 D/org.jivesoftware.smack.tcp.XMPPTCPConnection﹕ Trying to establish TCP connection to jabber.modum.by./80.249.82.72 at port 5220
2017-04-10_19-51-42 D/org.jivesoftware.smack.tcp.XMPPTCPConnection﹕ Established TCP connection to jabber.modum.by./80.249.82.72 at port 5220
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <stream:stream xmlns='jabber:client' to='xabber.modum.by' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' from='test@xabber.modum.by' xml:lang='en'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='bowovqkmyu5s0lk7lxwjxcg9mcog7c6j037wusjs' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><address xmlns='http://affinix.com/jabber/address'>95.78.162.156</address><auth xmlns='http://jabber.org/features/iq-auth'/><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'><required></required></starttls></stream:features>
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'></starttls>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <stream:stream xmlns='jabber:client' to='xabber.modum.by' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' from='test@xabber.modum.by' xml:lang='en'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='olma6tv3qe12vao7y5ebcycf58axr2jgo6qu0myq' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><compression xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><address xmlns='http://affinix.com/jabber/address'>95.78.162.156</address><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'></auth></stream:features>
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AHRlc3QAc2RAI2ZrRHM2U2Q=</auth>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'></success>
2017-04-10_19-51-42 D/org.jivesoftware.smack.AbstractXMPPConnection﹕ Waiting for last features to be received before continuing with resource binding
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <stream:stream xmlns='jabber:client' to='xabber.modum.by' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' from='test@xabber.modum.by' id='olma6tv3qe12vao7y5ebcycf58axr2jgo6qu0myq' xml:lang='en'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='ztw9i48njkzhwfm5pjxs3dga4baq91r8vnidsr1b' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><compression xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><ack:ack/><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'><required/></bind><unbind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/><ver xmlns='urn:xmpp:features:rosterver'></ver></stream:features>
2017-04-10_19-51-42 D/Smack﹕ SENT (1): <iq id='5AN92-35' type='set'><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'><resource>Xabber_7O8Vlr6q</resource></bind></iq>
2017-04-10_19-51-42 D/Smack﹕ RECV (1): <iq xmlns='jabber:client' id='5AN92-35' type='result'><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'><jid>test@xabber.modum.by/Xabber_7O8Vlr6q</jid></bind></iq>


------------------- but second and other attemps (on the same connection) fails -------------------------------

2017-04-10_19-52-19 D/org.jivesoftware.smack.util.DNSUtil﹕ Resolved SRV RR for _xmpp-client._tcp.xabber.modum.by: jabber.modum.by:5220 prio:5:w:0
2017-04-10_19-52-19 D/org.jivesoftware.smack.tcp.XMPPTCPConnection﹕ Trying to establish TCP connection to jabber.modum.by./80.249.82.72 at port 5220
2017-04-10_19-52-19 D/org.jivesoftware.smack.tcp.XMPPTCPConnection﹕ Established TCP connection to jabber.modum.by./80.249.82.72 at port 5220
2017-04-10_19-52-19 D/Smack﹕ SENT (1): <stream:stream xmlns='jabber:client' to='xabber.modum.by' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' from='test@xabber.modum.by' xml:lang='en'>
2017-04-10_19-52-19 D/Smack﹕ RECV (1): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='1s8hed7yaz2ogcsrnj1pa9kysyqj01v1t4j8hq7s' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
2017-04-10_19-52-19 D/Smack﹕ RECV (1): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><address xmlns='http://affinix.com/jabber/address'>95.78.162.156</address><auth xmlns='http://jabber.org/features/iq-auth'/><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'><required></required></starttls></stream:features>
2017-04-10_19-52-19 D/Smack﹕ SENT (1): <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'></starttls>
2017-04-10_19-52-19 D/Smack﹕ RECV (1): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
2017-04-10_19-52-19 W/ConnectionThread: test@xabber.modum.by/Xabber_7O8Vlr6q: org.jivesoftware.smack.SmackException: javax.net.ssl.SSLHandshakeException: Connection closed by peer
	at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.parsePackets(XMPPTCPConnection.java:1058)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.access$300(XMPPTCPConnection.java:980)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader$1.run(XMPPTCPConnection.java:996)
	at java.lang.Thread.run(Thread.java:818)
Caused by: javax.net.ssl.SSLHandshakeException: Connection closed by peer
	at com.android.org.conscrypt.NativeCrypto.SSL_do_handshake(Native Method)
	at com.android.org.conscrypt.OpenSSLSocketImpl.startHandshake(OpenSSLSocketImpl.java:324)
	at com.android.org.conscrypt.OpenSSLSocketImpl.waitForHandshake(OpenSSLSocketImpl.java:629)
	at com.android.org.conscrypt.OpenSSLSocketImpl.getInputStream(OpenSSLSocketImpl.java:591)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.initReaderAndWriter(XMPPTCPConnection.java:656)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.proceedTLSReceived(XMPPTCPConnection.java:793)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.access$1200(XMPPTCPConnection.java:149)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.parsePackets(XMPPTCPConnection.java:1053)
	... 3 more



------------------- fragment of jabberd2 logs ---------------------------------

sx (io.c:266) decoded read data (162 bytes): <stream:stream xmlns='jabber:client' to='xabber.modum.by' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' from='test@xabber.modum.by' xml:lang='en'>
sx (server.c:126) stream request: to xabber.modum.by from test@xabber.modum.by version 1.0
sx (server.c:141) 10 state change from 0 to 1
sx (server.c:159) stream id is 2jei5t2pinoqnxqmn3qn7t5793dxc8xfrbyw50ln
sx (server.c:202) prepared stream response: <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='2jei5t2pinoqnxqmn3qn7t5793dxc8xfrbyw50ln' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
sx (io.c:282) tag 10 event 1 data 0x0
sx (io.c:357) 10 ready for writing
sx (io.c:312) encoding 275 bytes for writing: <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' to='test@xabber.modum.by' from='xabber.modum.by' version='1.0' id='2jei5t2pinoqnxqmn3qn7t5793dxc8xfrbyw50ln' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
sx (chain.c:79) calling io write chain
sx (io.c:378) handing app 275 bytes to write
sx (io.c:379) tag 10 event 3 data 0x1dfbadb0
sx (server.c:29) stream established
sx (server.c:39) 10 state change from 1 to 3
sx (server.c:40) tag 10 event 4 data 0x0
sx (server.c:45) building features nad
sx (address.c:34) adding address feature
sx (sasl.c:260) ssl not established yet but the app requires it, not offering mechanisms
sx (ssl.c:281) offering starttls
sx (io.c:412) tag 10 event 0 data 0x0
sx (io.c:357) 10 ready for writing
sx (io.c:312) encoding 289 bytes for writing: <stream:features xmlns:stream='http://etherx.jabber.org/streams'><address xmlns='http://affinix.com/jabber/address'>95.78.162.156</address><auth xmlns='http://jabber.org/features/iq-auth'/><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'><required></required></starttls></stream:features>
sx (chain.c:79) calling io write chain
sx (io.c:378) handing app 289 bytes to write
sx (io.c:379) tag 10 event 3 data 0x1dfbadb0
sx (io.c:412) tag 10 event 0 data 0x0
sx (io.c:206) 10 ready for reading
sx (io.c:212) tag 10 event 2 data 0x1dfbadb0
sx (io.c:231) passed 61 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:266) decoded read data (61 bytes): <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'></starttls>
sx (io.c:96) completed nad: <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'></starttls>
sx (chain.c:119) calling nad read chain
sx (ssl.c:203) starttls requested, setting up
sx (io.c:282) tag 10 event 1 data 0x0
sx (io.c:357) 10 ready for writing
sx (io.c:312) encoding 50 bytes for writing: <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
sx (chain.c:79) calling io write chain
sx (io.c:378) handing app 50 bytes to write
sx (io.c:379) tag 10 event 3 data 0x1dfba260
sx (ssl.c:156) preparing for starttls
sx (sx.c:158) resetting stream state
sx (sx.c:82) freeing sx for 10
sx (sx.c:65) allocated new sx for 10
sx (sx.c:213) finished resetting stream state
sx (server.c:260) doing server init for sx 10 
sx (ssl.c:810) preparing for ssl accept for 10 to xabber.modum.by
sx (ssl.c:818) using configured ssl context for 10
sx (chain.c:28) adding io plugin
sx (server.c:272) waiting for stream header
sx (server.c:275) tag 10 event 0 data 0x0
sx (io.c:412) tag 10 event 0 data 0x0
sx (io.c:206) 10 ready for reading
sx (io.c:212) tag 10 event 2 data 0x1dfba260
sx (io.c:231) passed 194 read bytes
sx (chain.c:93) calling io read chain
sx (ssl.c:581) in _sx_ssl_rio
sx (ssl.c:585) loading 194 bytes into ssl read buffer
sx (ssl.c:406) secure channel not established, handshake in progress
sx (ssl.c:445) openssl error: error:140D9115:SSL routines:ssl_get_prev_session:session id context uninitialized
sx (io.c:522) 10 state change from 0 to 6
sx (io.c:523) tag 10 event 7 data 0x0
sx (sx.c:82) freeing sx for 10
sx (sx.c:115) freeing 6 env plugins
sx (sx.c:65) allocated new sx for 10
sx (server.c:260) doing server init for sx 10 
sx (server.c:272) waiting for stream header