[#CONJ-360] Connection timeout issue due to DNS lookup

[CONJ-360] Connection timeout issue due to DNS lookup Created: 2016-10-04 Updated: 2016-10-12 Resolved: 2016-10-05
Status:	Closed
Project:	MariaDB Connector/J
Component/s:	Failover
Affects Version/s:	all
Fix Version/s:	1.5.4

Type:

Bug

Priority:

Critical

Reporter:

Lukasz Strzeszkowski

Assignee:

Diego Dupin

Resolution:

Fixed

Votes:

Labels:

None

Environment:

Mac OS 10.12 Sierra, Netbeans 8.1/8.2

Attachments:

TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar

mariadbException.pcapng

ubuntu14_04LTS_log.txt

ubuntu16_04LTS_log.txt

Description

There is a problem to establish connection to Mariadb using java connector. DriverManager.getConnection returns an exception java.sql.SQLNonTransientConnectionException: Could not connect to 'db server ip':3306: unexpected end of stream, read 0 bytes from 4. This error occurs only with Mac os Sierra.
I have no firewall or anti virus software launched.
I found found that importing mysql-connector to the project solves the problem, even if I load a mariadb driver (Class.forName("org.mariadb.jdbc.Driver").newInstance()

Edit:
After investigation, problem rely on use of ManagementFactory.getRuntimeMXBean().getName() to get process PID. Java execute a DNS query to get hostname. If DNS isn't well configured on server, DNS request may take more time than server permit, and server will close socket. New implementation rely on JNA if present to avoid this problem

Comments

Comment by Diego Dupin [ 2016-10-04 ]

Hi, could you indicate the driver version you use ?
the lastest version is 1.5.3, could you try with this version ?

Class.forName("org.mariadb.jdbc.Driver").newInstance() is kinda deprecated.
The standard java way to connect is using DriverManager: example DriverManager.getConnection("jdbc:mariadb:://localhost:3306/testj", <user>, <password>);
if your connection String url is like "jdbc:mysql" and you have MySQL and MariaDB driver in classpath, one of the driver will be used randomly, that's not a good solution. Better to have only one driver.

Comment by Lukasz Strzeszkowski [ 2016-10-04 ]

I tried with driver 1.4.5, 1.5.2 and 1.5.3, with the same exception.
I changed the connection string "jdbc:mariadb://", as you but the exception still occurs.
There is no problem to connect to local database, but only to remote database.
I checked the network transmission in wireshark. The transmission sequence when exception occurs is only Server Greeting and Login Request, but no Response OK after that.

The exception is:
java.sql.SQLNonTransientConnectionException: Could not connect to 192.168.0.5:3306: unexpected end of stream, read 0 bytes from 4
at org.mariadb.jdbc.internal.util.ExceptionMapper.get(ExceptionMapper.java:125)
at org.mariadb.jdbc.internal.util.ExceptionMapper.throwException(ExceptionMapper.java:71)
at org.mariadb.jdbc.Driver.connect(Driver.java:109)
at java.sql.DriverManager.getConnection(DriverManager.java:664)
at java.sql.DriverManager.getConnection(DriverManager.java:247)

Comment by Vladislav Vaintroub [ 2016-10-04 ]

Server closed the socket, this is basically what this message says. can you connect with command line client?

Comment by Lukasz Strzeszkowski [ 2016-10-04 ]

I can connect with a command line client, with Navicat for mariadb, with my java app using mysql connector.

Comment by Diego Dupin [ 2016-10-04 ]

If you checked packets with wireshark and have seen greetings and login only, it seems login packet format is wrong for the server, and so server close connection. That's very strange, except if server is very old (like 4.x - that would explain things, since MySQL driver is still compatible with very old server)
Best would be send wireshark files (with your ip address and server address) that would permit to identify the issue

I know there is a lot of informations, but to understand the problem, can you give those informations too ?

server information : environment, is it a MariaDB server ? which version ?
the exact connection url string to know your current options
java provider information

Comment by Lukasz Strzeszkowski [ 2016-10-04 ]

The server is
mysql Ver 15.1 Distrib 10.1.18-MariaDB, for debian-linux-gnu (x86_64) using readline 5.2
installed on Ubuntu 14.04 LTS
Connection string is:
jdbc:mariadb://192.168.0.5:3306/02814290_program?user=test_user&password=haslo123&useUnicode=true&allowMultiQueries=true
Java on the server
java version "1.8.0_91"
Java(TM) SE Runtime Environment (build 1.8.0_91-b14)
Java HotSpot(TM) 64-Bit Server VM (build 25.91-b14, mixed mode)
Java on my mac
java version "1.8.0_101"
Java(TM) SE Runtime Environment (build 1.8.0_101-b13)
Java HotSpot(TM) 64-Bit Server VM (build 25.101-b13, mixed mode)

Comment by Diego Dupin [ 2016-10-04 ]

That's unusual : packets are well formed. The problem is driver answer server (login request) more than 5 seconds after the server greeting packet.
Server wait a 5 seconds, and without answer close the connection.

I'm wondering why driver did not send reply in 5 sec, driver only work here is one little sha-1 encryption for password.

Does it occur at all connections ?
It seems the value of server variable "connect_timeout" is set to 5 seconds (show variables like 'connect_timeout').
can you temporary set this value to 10seconds (set GLOBAL connect_timeout = 10 and retry to connect ?

Comment by Lukasz Strzeszkowski [ 2016-10-04 ]

The problem does not occur when connect_timeout is set >6 seconds.
Establishing connection takes much more time then on mac os 10.11 and other not mac os, but it works now.
Do you have any idea what could cause this strange functioning of the connector?

Comment by Diego Dupin [ 2016-10-04 ]

Good that solve the problem, but that cannot be a definive solution.

Could you execute the jar i've join to these JIRA with the following command :
java -jar target/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar "jdbc:mariadb://192.168.0.5:3306/02814290_program?user=test_user&password=haslo123&useUnicode=true&allowMultiQueries=true&log=true"

The JAR is a "fat" jar with modified driver + logger : it connect to URL + execute a "SELECT 1" query with lot of logs, to identify what cause this issue

(sources shared on github : https://github.com/rusher/TestConector)

Comment by Lukasz Strzeszkowski [ 2016-10-04 ]

19:55:56,490 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
19:55:56,490 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
19:55:56,490 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml]
19:55:56,500 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@29444d75 - URL [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml] is not of type file
19:55:56,558 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
19:56:01,622 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
19:56:01,626 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
19:56:01,700 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - This appender no longer admits a layout as a sub-component, set an encoder instead.
19:56:01,700 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
19:56:01,700 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
19:56:01,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mariadb] to TRACE
19:56:01,700 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.mariadb] to false
19:56:01,700 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[org.mariadb]
19:56:01,701 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to ERROR
19:56:01,701 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
19:56:01,701 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
19:56:01,701 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2280cdac - Registering current configuration as safe fallback point

2016-10-04 19:56:01.713 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - creating socket to server 192.168.0.5
2016-10-04 19:56:01.716 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connect to 192.168.0.5:3306
2016-10-04 19:56:01.724 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connected
2016-10-04 19:56:01.726 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - waiting for server "greeting" packet
2016-10-04 19:56:01.727 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:0 length:98 data:0A352E352E352D31302E312E31382D4D6172696144422D317E7472757374790022000000433375756A35693000FFF72102003FA0150000000000000000000052303453286A61263723477B006D7973716C5F6E61746976655F70617373776F726400
2016-10-04 19:56:01.728 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - received server "greeting" packet
2016-10-04 19:56:01.729 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - Sending "Login Request" packet
2016-10-04 19:56:06.807 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - "Login Request" packet send
2016-10-04 19:56:06.809 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:7 data:00000002000000
2016-10-04 19:56:06.810 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:106 data:0353484F57205641524941424C4553205748455245205661726961626C655F6E616D6520696E2028276D61785F616C6C6F7765645F7061636B6574272C202773797374656D5F74696D655F7A6F6E65272C202774696D655F7A6F6E65272C202773716C5F6D6F
2016-10-04 19:56:06.812 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:02
2016-10-04 19:56:06.813 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:100 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530D5661726961626C655F6E616D650D5641524941424C455F4E414D450C2100C0000000FD0100000000
2016-10-04 19:56:06.814 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:93 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530556616C75650E5641524941424C455F56414C55450C210000180000FD0100000000
2016-10-04 19:56:06.814 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:4 length:5 data:FE00002200
2016-10-04 19:56:06.819 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:29
2016-10-04 19:56:06.819 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x24
2016-10-04 19:56:06.819 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):6D61785F616C6C6F7765645F7061636B6574
2016-10-04 19:56:06.819 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):323638343335343536
2016-10-04 19:56:06.819 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:6 length:65
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x8
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73716C5F6D6F6465
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):49474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:7 length:22
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x22
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73797374656D5F74696D655F7A6F6E65
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):43455354
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:8 length:17
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x9
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):74696D655F7A6F6E65
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):53595354454D
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:9 length:5
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-04 19:56:06.820 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00002200
2016-10-04 19:56:06.821 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data max_allowed_packet : 268435456
2016-10-04 19:56:06.821 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data sql_mode : IGNORE_SPACE,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION
2016-10-04 19:56:06.821 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data system_time_zone : CEST
2016-10-04 19:56:06.821 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data time_zone : SYSTEM
2016-10-04 19:56:06.821 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:112 data:037365742073657373696F6E206175746F636F6D6D69743D312C73716C5F6D6F64653D2749474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E2C5354524943545F5452414E535F544142
2016-10-04 19:56:06.822 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:7 data:00000002000000
2016-10-04 19:56:06.830 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:9 data:0353454C45
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:01
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:23 data:036465660000000131000C3F0001000000088100000000
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:5 data:FE00000200
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:4 length:2
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x1
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):31
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:5
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-04 19:56:06.832 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00000200
2016-10-04 19:56:06.833 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:1 data:

Comment by Diego Dupin [ 2016-10-05 ]

nice !
ok, that indicate that problem reside in driver : the "Login request" packet creation take 5.1s.
Reading code, i see no reason for that. I've change the Jar file. The new jar has logging information for each command the driver execute for the Login request creation.
Reading code, it seems the only thing that may take some time is the SHA-1 password encryption, but normally does'nt take seconds.
Could you re-execute the same command to identify the exact command that cause problem ?

Comment by Lukasz Strzeszkowski [ 2016-10-05 ]

I obtained only from execution of attached jar
no main manifest attribute, in TestConnector-1.0-SNAPSHOT.jar
I think there is something wrong with it.

Comment by Diego Dupin [ 2016-10-05 ]

right, sorry, uploaded jar wasn't the fat jar, only "normal" jar, without dependencies and manifest.
Jar updated.

Comment by Lukasz Strzeszkowski [ 2016-10-05 ]

java -jar TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar "jdbc:mariadb://192.168.0.5:3306/02814290_program?user=test_user&password=haslo123&useUnicode=true&allowMultiQueries=true&log=true"
10:21:33,072 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
10:21:33,072 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
10:21:33,072 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml]
10:21:33,081 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@29444d75 - URL [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml] is not of type file
10:21:33,115 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
10:21:38,171 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
10:21:38,175 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
10:21:38,228 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - This appender no longer admits a layout as a sub-component, set an encoder instead.
10:21:38,228 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
10:21:38,228 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
10:21:38,228 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mariadb] to TRACE
10:21:38,228 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.mariadb] to false
10:21:38,228 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[org.mariadb]
10:21:38,229 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to ERROR
10:21:38,229 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
10:21:38,229 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
10:21:38,229 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2280cdac - Registering current configuration as safe fallback point

2016-10-05 10:21:38.240 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - creating socket to server 192.168.0.5
2016-10-05 10:21:38.244 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connect to 192.168.0.5:3306
2016-10-05 10:21:38.250 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connected
2016-10-05 10:21:38.250 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - initializing reader
2016-10-05 10:21:38.252 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - initializing writer
2016-10-05 10:21:38.253 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - waiting for server "greeting" packet
2016-10-05 10:21:38.254 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:0 length:98 data:0A352E352E352D31302E312E31382D4D6172696144422D317E74727573747900620100007A786C386C3B223600FFF72102003FA015000000000000000000002760317061764C5A61576E44006D7973716C5F6E61746976655F70617373776F726400
2016-10-05 10:21:38.254 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - received server "greeting" packet
2016-10-05 10:21:38.254 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - handling server "greeting" packet data
2016-10-05 10:21:38.255 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - finished handling server "greeting" packet data
2016-10-05 10:21:38.255 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - beginning handling driver login packet
2016-10-05 10:21:38.256 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - Sending "Login Request" packet
2016-10-05 10:21:38.256 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake
2016-10-05 10:21:38.256 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake buffer
2016-10-05 10:21:38.256 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake before encrypting password
2016-10-05 10:21:38.256 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - encrypting SHA-1 password. password length:8 server seed length:20
2016-10-05 10:21:38.261 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 created
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 digest 1
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 reset 1
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 digest 2
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 reset 1
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 update 1
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 update 2
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest before scramble password
2016-10-05 10:21:38.262 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest after scramble password
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake after encrypting password
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing client capabilities
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing client capabilities second part
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing user name for SSO
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing PLUGIN_AUTH_LENENC_CLIENT_DATA
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing CONNECT_WITH_DB
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing PLUGIN_AUTH
2016-10-05 10:21:38.262 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing CONNECT_ATTRS
2016-10-05 10:21:43.339 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake end writing CONNECT_ATTRS
2016-10-05 10:21:43.340 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing to socket
2016-10-05 10:21:43.340 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake end writing to socket
2016-10-05 10:21:43.340 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake releasing buffer
2016-10-05 10:21:43.340 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake buffer released
2016-10-05 10:21:43.340 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - "Login Request" packet send
2016-10-05 10:21:43.343 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:7 data:00000002000000
2016-10-05 10:21:43.344 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:106 data:0353484F57205641524941424C4553205748455245205661726961626C655F6E616D6520696E2028276D61785F616C6C6F7765645F7061636B6574272C202773797374656D5F74696D655F7A6F6E65272C202774696D655F7A6F6E65272C202773716C5F6D6F
2016-10-05 10:21:43.348 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:02
2016-10-05 10:21:43.349 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:100 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530D5661726961626C655F6E616D650D5641524941424C455F4E414D450C2100C0000000FD0100000000
2016-10-05 10:21:43.350 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:93 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530556616C75650E5641524941424C455F56414C55450C210000180000FD0100000000
2016-10-05 10:21:43.350 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:4 length:5 data:FE00002200
2016-10-05 10:21:43.355 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:29
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x24
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):6D61785F616C6C6F7765645F7061636B6574
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):323638343335343536
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:6 length:65
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x8
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73716C5F6D6F6465
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):49474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:7 length:22
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x22
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73797374656D5F74696D655F7A6F6E65
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):43455354
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:8 length:17
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x9
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):74696D655F7A6F6E65
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):53595354454D
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:9 length:5
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-05 10:21:43.356 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00002200
2016-10-05 10:21:43.357 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data max_allowed_packet : 268435456
2016-10-05 10:21:43.357 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data sql_mode : IGNORE_SPACE,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION
2016-10-05 10:21:43.358 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data system_time_zone : CEST
2016-10-05 10:21:43.358 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data time_zone : SYSTEM
2016-10-05 10:21:43.358 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:112 data:037365742073657373696F6E206175746F636F6D6D69743D312C73716C5F6D6F64653D2749474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E2C5354524943545F5452414E535F544142
2016-10-05 10:21:43.359 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:7 data:00000002000000
2016-10-05 10:21:43.367 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:9 data:0353454C45
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:01
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:23 data:036465660000000131000C3F0001000000088100000000
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:5 data:FE00000200
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:4 length:2
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x1
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):31
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:5
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-05 10:21:43.369 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00000200
2016-10-05 10:21:43.370 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:1 data:

Comment by Diego Dupin [ 2016-10-05 ]

Last debug point to the use of ManagementFactory.getRuntimeMXBean() to get current PID may take some time for getting localhost real name (depending to JVM implementation).

new JAR has been uploaded with correction.
Could you please run a last time to confirm correction?

Comment by Lukasz Strzeszkowski [ 2016-10-05 ]

11:55:06,435 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
11:55:06,435 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
11:55:06,435 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml]
11:55:06,445 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@29444d75 - URL [jar:file:/Users/lukaszstrzeszkowski/Desktop/TestConnector-1.0-SNAPSHOT-jar-with-dependencies.jar!/logback.xml] is not of type file
11:55:06,479 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
11:55:11,517 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
11:55:11,521 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
11:55:11,576 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - This appender no longer admits a layout as a sub-component, set an encoder instead.
11:55:11,576 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
11:55:11,576 |-WARN in ch.qos.logback.core.ConsoleAppender[STDOUT] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
11:55:11,576 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.mariadb] to TRACE
11:55:11,576 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.mariadb] to false
11:55:11,577 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[org.mariadb]
11:55:11,577 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to ERROR
11:55:11,577 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
11:55:11,577 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
11:55:11,578 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2280cdac - Registering current configuration as safe fallback point

2016-10-05 11:55:11.589 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - creating socket to server 192.168.0.5
2016-10-05 11:55:11.592 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connect to 192.168.0.5:3306
2016-10-05 11:55:11.599 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - socket connected
2016-10-05 11:55:11.599 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - initializing reader
2016-10-05 11:55:11.601 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - initializing writer
2016-10-05 11:55:11.602 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - waiting for server "greeting" packet
2016-10-05 11:55:11.603 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:0 length:98 data:0A352E352E352D31302E312E31382D4D6172696144422D317E74727573747900640100006F334D694B793F6F00FFF72102003FA015000000000000000000003144425E34286A5F7B6C3047006D7973716C5F6E61746976655F70617373776F726400
2016-10-05 11:55:11.603 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - received server "greeting" packet
2016-10-05 11:55:11.603 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - handling server "greeting" packet data
2016-10-05 11:55:11.604 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - finished handling server "greeting" packet data
2016-10-05 11:55:11.604 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - beginning handling driver login packet
2016-10-05 11:55:11.605 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - Sending "Login Request" packet
2016-10-05 11:55:11.605 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake
2016-10-05 11:55:11.605 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake buffer
2016-10-05 11:55:11.605 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake before encrypting password
2016-10-05 11:55:11.605 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - encrypting SHA-1 password. password length:8 server seed length:20
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 created
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 digest 1
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 reset 1
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 digest 2
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 reset 1
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 update 1
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest for SHA-1 update 2
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest before scramble password
2016-10-05 11:55:11.611 [main] DEBUG org.mariadb.jdbc.internal.util.Utils - MessageDigest after scramble password
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake after encrypting password
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing client capabilities
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing client capabilities second part
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing user name for SSO
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing PLUGIN_AUTH_LENENC_CLIENT_DATA
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing CONNECT_WITH_DB
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing PLUGIN_AUTH
2016-10-05 11:55:11.611 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing CONNECT_ATTRS
2016-10-05 11:55:11.612 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake end writing CONNECT_ATTRS
2016-10-05 11:55:11.612 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake writing to socket
2016-10-05 11:55:11.612 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake end writing to socket
2016-10-05 11:55:11.612 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake releasing buffer
2016-10-05 11:55:11.612 [main] DEBUG o.m.j.i.p.s.SendHandshakeResponsePacket - init Handshake buffer released
2016-10-05 11:55:11.612 [main] TRACE o.m.j.i.p.AbstractConnectProtocol - "Login Request" packet send
2016-10-05 11:55:11.614 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:7 data:00000002000000
2016-10-05 11:55:11.615 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:106 data:��0353484F57205641524941424C4553205748455245205661726961626C655F6E616D6520696E2028276D61785F616C6C6F7765645F7061636B6574272C202773797374656D5F74696D655F7A6F6E65272C202774696D655F7A6F6E65272C202773716C5F6D6F
2016-10-05 11:55:11.618 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:02
2016-10-05 11:55:11.618 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:100 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530D5661726961626C655F6E616D650D5641524941424C455F4E414D450C2100C0000000FD0100000000
2016-10-05 11:55:11.619 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:93 data:0364656612696E666F726D6174696F6E5F736368656D611153455353494F4E5F5641524941424C45531153455353494F4E5F5641524941424C45530556616C75650E5641524941424C455F56414C55450C210000180000FD0100000000
2016-10-05 11:55:11.619 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:4 length:5 data:FE00002200
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:29
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x24
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):6D61785F616C6C6F7765645F7061636B6574
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):323638343335343536
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:6 length:65
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x8
2016-10-05 11:55:11.624 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73716C5F6D6F6465
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):49474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:7 length:22
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x22
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):73797374656D5F74696D655F7A6F6E65
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):43455354
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:8 length:17
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x9
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):74696D655F7A6F6E65
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):53595354454D
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:9 length:5
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-05 11:55:11.625 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00002200
2016-10-05 11:55:11.625 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data max_allowed_packet : 268435456
2016-10-05 11:55:11.626 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data sql_mode : IGNORE_SPACE,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION
2016-10-05 11:55:11.626 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data system_time_zone : CEST
2016-10-05 11:55:11.626 [main] DEBUG o.m.j.i.p.AbstractConnectProtocol - server data time_zone : SYSTEM
2016-10-05 11:55:11.626 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:112 data:��037365742073657373696F6E206175746F636F6D6D69743D312C73716C5F6D6F64653D2749474E4F52455F53504143452C4E4F5F4155544F5F4352454154455F555345522C4E4F5F454E47494E455F535542535449545554494F4E2C5354524943545F5452414E535F544142
2016-10-05 11:55:11.627 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:7 data:00000002000000
2016-10-05 11:55:11.636 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:9 data:��0353454C45
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:1 length:1 data:01
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:2 length:23 data:036465660000000131000C3F0001000000088100000000
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet seq:3 length:5 data:FE00000200
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:4 length:2
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x1
2016-10-05 11:55:11.637 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):31
2016-10-05 11:55:11.638 [main] TRACE o.m.j.i.s.MariaDbBufferedInputStream - read packet seq:5 length:5
2016-10-05 11:55:11.638 [main] TRACE o.m.j.i.q.r.MariaSelectResultSet - read packet data(part):0x596
2016-10-05 11:55:11.638 [main] TRACE o.m.j.i.p.read.ReadPacketFetcher - read packet data(part):00000200
2016-10-05 11:55:11.638 [main] TRACE o.m.j.i.stream.PacketOutputStream - send packet seq:0 length:1 data:��

Comment by Diego Dupin [ 2016-10-05 ]

37ms to connect. That's way better.
Ok, so correction has to be tested in all environment, but it's now identified.

Comment by Lukasz Strzeszkowski [ 2016-10-05 ]

Thats great. I checked the last jar on ubuntu 16.04 LTS and results are the same as on mac os 10.12, and the second one from ubuntu 14.04LTS.
The log file: ubuntu16_04LTS_log.txt
ubuntu14_04LTS_log.txt

Comment by Diego Dupin [ 2016-10-05 ]

will be release in next corrective release (1.5.4)
commits :

Generated at Thu Feb 08 03:15:05 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.

[CONJ-360] Connection timeout issue due to DNS lookup Created: 2016-10-04 Updated: 2016-10-12 Resolved: 2016-10-05