kafka-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Sagar Rao (JIRA)" <j...@apache.org>
Subject [jira] [Created] (KAFKA-4371) Sporadic ConnectException shuts down the whole connect process
Date Thu, 03 Nov 2016 10:02:58 GMT
Sagar Rao created KAFKA-4371:
--------------------------------

             Summary: Sporadic ConnectException shuts down the whole connect process
                 Key: KAFKA-4371
                 URL: https://issues.apache.org/jira/browse/KAFKA-4371
             Project: Kafka
          Issue Type: Bug
            Reporter: Sagar Rao
            Priority: Critical


I had setup a 2 node distributed kafka-connect process. Everything went well and I could see
lot of data flowing into the relevant kafka topics.

After some time, JDBCUtils.getCurrentTimeOnDB threw a ConnectException with the following
stacktrace:

The last packet successfully received from the server was 792 milliseconds ago.  The last
packet sent successfully to the server was 286 milliseconds ago. (io.confluent.connect.jdbc.source.JdbcSourceTask:234)
[2016-11-02 12:42:06,116] ERROR Failed to get current time from DB using query select CURRENT_TIMESTAMP;
on database MySQL (io.confluent.connect.jdbc.util.JdbcUtils:226)
com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure

The last packet successfully received from the server was 1,855 milliseconds ago.  The last
packet sent successfully to the server was 557 milliseconds ago.
       at sun.reflect.GeneratedConstructorAccessor51.newInstance(Unknown Source)
       at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
       at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
       at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
       at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1117)
       at com.mysql.jdbc.MysqlIO.send(MysqlIO.java:3829)
       at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2449)
       at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2629)
       at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2719)
       at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2155)
       at com.mysql.jdbc.PreparedStatement.execute(PreparedStatement.java:1379)
       at com.mysql.jdbc.StatementImpl.createResultSetUsingServerFetch(StatementImpl.java:651)
       at com.mysql.jdbc.StatementImpl.executeQuery(StatementImpl.java:1527)
       at io.confluent.connect.jdbc.util.JdbcUtils.getCurrentTimeOnDB(JdbcUtils.java:220)
       at io.confluent.connect.jdbc.source.TimestampIncrementingTableQuerier.executeQuery(TimestampIncrementingTableQuerier.java:157)
       at io.confluent.connect.jdbc.source.TableQuerier.maybeStartQuery(TableQuerier.java:78)
       at io.confluent.connect.jdbc.source.TimestampIncrementingTableQuerier.maybeStartQuery(TimestampIncrementingTableQuerier.java:57)
       at io.confluent.connect.jdbc.source.JdbcSourceTask.poll(JdbcSourceTask.java:207)
       at org.apache.kafka.connect.runtime.WorkerSourceTask.execute(WorkerSourceTask.java:155)
       at org.apache.kafka.connect.runtime.WorkerTask.doRun(WorkerTask.java:140)
       at org.apache.kafka.connect.runtime.WorkerTask.run(WorkerTask.java:175)
       at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
       at java.util.concurrent.FutureTask.run(FutureTask.java:266)
       at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
       at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
       at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketException: Broken pipe (Write failed)
       at java.net.SocketOutputStream.socketWrite0(Native Method)
       at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:109)
       at java.net.SocketOutputStream.write(SocketOutputStream.java:153)
       at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
       at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
       at com.mysql.jdbc.MysqlIO.send(MysqlIO.java:3810)
       ... 20 more

This was just a minor glitch to the connection as the ec2 isntances are able to connect to
the Mysql Aurora instances without any issues.

But, after this exception(which is there a number of times), none of the connectors' tasks
are executing. Beyond this, all I see in the logs is 

[2016-11-02 16:17:41,983] ERROR Failed to run query for table TimestampIncrementingTableQuerier{name='eng_match_series',
query='null', topicPrefix='ci-eng-', timestampColumn='modified', incrementingColumn='id'}:
com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: No operations allowed
after statement closed. (io.confluent.connect.jdbc.source.JdbcSourceTask:234)

Is this expected behaviour? I restarted the connector using REST apis but that didn't help.
How do we handle such scenarios? 

Eventually I had to delete the connector and restart.

The kafka version I am using is 0.10.0.1-cp1 as there were some custom changes we needed to
make at the Connect level.




--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Mime
View raw message