kafka-jira mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Mehmet Soner (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (KAFKA-5962) java.io.IOException: Map failed
Date Fri, 22 Sep 2017 07:33:00 GMT

     [ https://issues.apache.org/jira/browse/KAFKA-5962?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Mehmet Soner updated KAFKA-5962:
--------------------------------
    Description: 
Step to reproduce bugs:

*1) Starting zookeeper by using below command.*
zookeeper-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/zookeeper.properties

*2) Starting kafka by using below command.*
kafka-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/server0.properties

*3) Writing data to topic*
/prov/users/sas/bin$ $KAFKA_HOME/bin/kafka-console-producer.sh --broker-list 172.31.19.85:9092
--topic ssmtopup_topic
>test1
>test2
>test3
>test4
>test5

*server.log*
[2017-09-22 09:51:24,467] INFO Updated PartitionLeaderEpoch. New: {epoch:7, offset:0}, Current:
{epoch:-1, offset-1} for Partition: ssmtopup_topic-0. Cache now contains 0 entries. (kafka.server.epoch.LeaderEpochFileCache)
[2017-09-22 09:52:23,819] INFO [Group Metadata Manager on Broker 0]: Removed 0 expired offsets
in 0 milliseconds. (kafka.coordinator.group.GroupMetadataManager)

*4) Exiting by pressing CTRL+C*

*5) Shutting down kafka by using below command*
*a) finding pid by uisin below command*
jps -lm
*b) graceful shutdown*
kill -15 

*You can find error below*
*server.log *

[2017-09-22 09:52:26,179] INFO [Kafka Server 0], shutting down (kafka.server.KafkaServer)
[2017-09-22 09:52:26,219] INFO [Kafka Server 0], Starting controlled shutdown (kafka.server.KafkaServer)
[2017-09-22 09:52:26,346] INFO [Kafka Server 0], Controlled shutdown succeeded (kafka.server.KafkaServer)
[2017-09-22 09:52:26,356] INFO [Socket Server on Broker 0], Shutting down (kafka.network.SocketServer)
[2017-09-22 09:52:26,384] INFO [Socket Server on Broker 0], Shutdown completed (kafka.network.SocketServer)
[2017-09-22 09:52:26,386] INFO [Kafka Request Handler on Broker 0], shutting down (kafka.server.KafkaRequestHandlerPool)
[2017-09-22 09:52:26,395] INFO [Kafka Request Handler on Broker 0], shut down completely (kafka.server.KafkaRequestHandlerPool)
[2017-09-22 09:52:26,411] INFO [ThrottledRequestReaper-Fetch]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,032] INFO [ThrottledRequestReaper-Fetch]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Fetch]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Produce]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Request]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,988] INFO [KafkaApi-0] Shutdown complete. (kafka.server.KafkaApis)
[2017-09-22 09:52:27,991] INFO [ExpirationReaper-0-topic]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,082] INFO [ExpirationReaper-0-topic]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,088] INFO [ExpirationReaper-0-topic]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,096] INFO [Transaction Coordinator 0]: Shutting down. (kafka.coordinator.transaction.TransactionCoordinator)
[2017-09-22 09:52:28,100] INFO [ProducerId Manager 0]: Shutdown complete: last producerId
assigned 7000 (kafka.coordinator.transaction.ProducerIdManager)
[2017-09-22 09:52:28,102] INFO [Transaction State Manager 0]: Shutdown complete (kafka.coordinator.transaction.TransactionStateManager)
[2017-09-22 09:52:28,102] INFO [Transaction Marker Channel Manager 0]: Shutting down (kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Stopped (kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Shutdown completed
(kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,105] INFO [Transaction Coordinator 0]: Shutdown complete. (kafka.coordinator.transaction.TransactionCoordinator)
[2017-09-22 09:52:28,107] INFO [GroupCoordinator 0]: Shutting down. (kafka.coordinator.group.GroupCoordinator)
[2017-09-22 09:52:28,108] INFO [ExpirationReaper-0-Heartbeat]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,114] INFO [GroupCoordinator 0]: Shutdown complete. (kafka.coordinator.group.GroupCoordinator)
[2017-09-22 09:52:28,116] INFO [Replica Manager on Broker 0]: Shutting down (kafka.server.ReplicaManager)
[2017-09-22 09:52:28,117] INFO [ReplicaFetcherManager on broker 0] shutting down (kafka.server.ReplicaFetcherManager)
[2017-09-22 09:52:28,122] INFO [ReplicaFetcherManager on broker 0] shutdown completed (kafka.server.ReplicaFetcherManager)
[2017-09-22 09:52:28,122] INFO [ExpirationReaper-0-Fetch]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Produce]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,319] INFO [ExpirationReaper-0-DeleteRecords]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,400] INFO [Replica Manager on Broker 0]: Shut down completely (kafka.server.ReplicaManager)
[2017-09-22 09:52:28,403] INFO Shutting down. (kafka.log.LogManager)
[2017-09-22 09:52:28,644] WARN Map failed (kafka.utils.CoreUtils$)
java.io.IOException: Map failed
        at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:943)
        at kafka.log.AbstractIndex.$anonfun$resize$1(AbstractIndex.scala:115)
        at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
        at kafka.log.AbstractIndex.resize(AbstractIndex.scala:105)
        at kafka.log.AbstractIndex.$anonfun$trimToValidSize$1(AbstractIndex.scala:167)
        at scala.runtime.java8.JFunction0$mcV$sp.apply(JFunction0$mcV$sp.java:12)
        at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
        at kafka.log.AbstractIndex.trimToValidSize(AbstractIndex.scala:167)
        at kafka.log.AbstractIndex.close(AbstractIndex.scala:178)
        at kafka.log.LogSegment.$anonfun$close$3(LogSegment.scala:478)
        at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:78)
        at kafka.utils.Logging.swallowWarn(Logging.scala:94)
        at kafka.utils.Logging.swallowWarn$(Logging.scala:93)
        at kafka.utils.CoreUtils$.swallowWarn(CoreUtils.scala:48)
        at kafka.utils.Logging.swallow(Logging.scala:96)
        at kafka.utils.Logging.swallow$(Logging.scala:96)
        at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:48)
        at kafka.log.LogSegment.close(LogSegment.scala:478)
        at kafka.log.Log.$anonfun$close$2(Log.scala:530)
        at kafka.log.Log.$anonfun$close$2$adapted(Log.scala:530)
        at scala.collection.Iterator.foreach(Iterator.scala:929)
        at scala.collection.Iterator.foreach$(Iterator.scala:929)
        at scala.collection.AbstractIterator.foreach(Iterator.scala:1417)
        at scala.collection.IterableLike.foreach(IterableLike.scala:71)
        at scala.collection.IterableLike.foreach$(IterableLike.scala:70)
        at scala.collection.AbstractIterable.foreach(Iterable.scala:54)
        at kafka.log.Log.close(Log.scala:530)
        at kafka.log.LogManager.$anonfun$shutdown$7(LogManager.scala:280)
        at kafka.utils.CoreUtils$$anon$1.run(CoreUtils.scala:57)
        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:748)
Caused by: java.lang.OutOfMemoryError: Map failed
        at sun.nio.ch.FileChannelImpl.map0(Native Method)
        at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:940)
        ... 33 more
[2017-09-22 09:52:28,703] INFO Shutdown complete. (kafka.log.LogManager)
[2017-09-22 09:52:28,725] INFO Terminate ZkClient event thread. (org.I0Itec.zkclient.ZkEventThread)
[2017-09-22 09:52:28,734] INFO Processed session termination for sessionid: 0x15ea850ecaf0000
(org.apache.zookeeper.server.PrepRequestProcessor)
[2017-09-22 09:52:28,739] INFO Session: 0x15ea850ecaf0000 closed (org.apache.zookeeper.ZooKeeper)
[2017-09-22 09:52:28,743] INFO Closed socket connection for client /127.0.0.1:53962 which
had sessionid 0x15ea850ecaf0000 (org.apache.zookeeper.server.NIOServerCnxn)
[2017-09-22 09:52:28,745] INFO EventThread shut down for session: 0x15ea850ecaf0000 (org.apache.zookeeper.ClientCnxn)
[2017-09-22 09:52:28,753] INFO [Kafka Server 0], shut down completed (kafka.server.KafkaServer)

  was:
Step to reproduce bugs:

*1) Starting zookeeper by using below command.*
zookeeper-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/zookeeper.properties

*2) Starting kafka by using below command.*
kafka-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/server0.properties

*3) Writing data to topic*
/prov/users/sas/bin$ $KAFKA_HOME/bin/kafka-console-producer.sh --broker-list 172.31.19.85:9092
--topic ssmtopup_topic
>test1
>test2
>test3
>test4
>test5

*server.log*
[2017-09-22 09:51:24,467] INFO Updated PartitionLeaderEpoch. New: {epoch:7, offset:0}, Current:
{epoch:-1, offset-1} for Partition: ssmtopup_topic-0. Cache now contains 0 entries. (kafka.server.epoch.LeaderEpochFileCache)
[2017-09-22 09:52:23,819] INFO [Group Metadata Manager on Broker 0]: Removed 0 expired offsets
in 0 milliseconds. (kafka.coordinator.group.GroupMetadataManager)

*4) Exiting by pressing CTRL+C*

*5) Shutting down kafka by using below command*
*a) finding pid by uisin below command*
jps -lm
*b) graceful shutdown*
kill -15 

"You can find error below"
*server.log *

[2017-09-22 09:52:26,179] INFO [Kafka Server 0], shutting down (kafka.server.KafkaServer)
[2017-09-22 09:52:26,219] INFO [Kafka Server 0], Starting controlled shutdown (kafka.server.KafkaServer)
[2017-09-22 09:52:26,346] INFO [Kafka Server 0], Controlled shutdown succeeded (kafka.server.KafkaServer)
[2017-09-22 09:52:26,356] INFO [Socket Server on Broker 0], Shutting down (kafka.network.SocketServer)
[2017-09-22 09:52:26,384] INFO [Socket Server on Broker 0], Shutdown completed (kafka.network.SocketServer)
[2017-09-22 09:52:26,386] INFO [Kafka Request Handler on Broker 0], shutting down (kafka.server.KafkaRequestHandlerPool)
[2017-09-22 09:52:26,395] INFO [Kafka Request Handler on Broker 0], shut down completely (kafka.server.KafkaRequestHandlerPool)
[2017-09-22 09:52:26,411] INFO [ThrottledRequestReaper-Fetch]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,032] INFO [ThrottledRequestReaper-Fetch]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Fetch]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Produce]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Request]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
[2017-09-22 09:52:27,988] INFO [KafkaApi-0] Shutdown complete. (kafka.server.KafkaApis)
[2017-09-22 09:52:27,991] INFO [ExpirationReaper-0-topic]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,082] INFO [ExpirationReaper-0-topic]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,088] INFO [ExpirationReaper-0-topic]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,096] INFO [Transaction Coordinator 0]: Shutting down. (kafka.coordinator.transaction.TransactionCoordinator)
[2017-09-22 09:52:28,100] INFO [ProducerId Manager 0]: Shutdown complete: last producerId
assigned 7000 (kafka.coordinator.transaction.ProducerIdManager)
[2017-09-22 09:52:28,102] INFO [Transaction State Manager 0]: Shutdown complete (kafka.coordinator.transaction.TransactionStateManager)
[2017-09-22 09:52:28,102] INFO [Transaction Marker Channel Manager 0]: Shutting down (kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Stopped (kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Shutdown completed
(kafka.coordinator.transaction.TransactionMarkerChannelManager)
[2017-09-22 09:52:28,105] INFO [Transaction Coordinator 0]: Shutdown complete. (kafka.coordinator.transaction.TransactionCoordinator)
[2017-09-22 09:52:28,107] INFO [GroupCoordinator 0]: Shutting down. (kafka.coordinator.group.GroupCoordinator)
[2017-09-22 09:52:28,108] INFO [ExpirationReaper-0-Heartbeat]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,114] INFO [GroupCoordinator 0]: Shutdown complete. (kafka.coordinator.group.GroupCoordinator)
[2017-09-22 09:52:28,116] INFO [Replica Manager on Broker 0]: Shutting down (kafka.server.ReplicaManager)
[2017-09-22 09:52:28,117] INFO [ReplicaFetcherManager on broker 0] shutting down (kafka.server.ReplicaFetcherManager)
[2017-09-22 09:52:28,122] INFO [ReplicaFetcherManager on broker 0] shutdown completed (kafka.server.ReplicaFetcherManager)
[2017-09-22 09:52:28,122] INFO [ExpirationReaper-0-Fetch]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Produce]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,319] INFO [ExpirationReaper-0-DeleteRecords]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2017-09-22 09:52:28,400] INFO [Replica Manager on Broker 0]: Shut down completely (kafka.server.ReplicaManager)
[2017-09-22 09:52:28,403] INFO Shutting down. (kafka.log.LogManager)
[2017-09-22 09:52:28,644] WARN Map failed (kafka.utils.CoreUtils$)
java.io.IOException: Map failed
        at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:943)
        at kafka.log.AbstractIndex.$anonfun$resize$1(AbstractIndex.scala:115)
        at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
        at kafka.log.AbstractIndex.resize(AbstractIndex.scala:105)
        at kafka.log.AbstractIndex.$anonfun$trimToValidSize$1(AbstractIndex.scala:167)
        at scala.runtime.java8.JFunction0$mcV$sp.apply(JFunction0$mcV$sp.java:12)
        at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
        at kafka.log.AbstractIndex.trimToValidSize(AbstractIndex.scala:167)
        at kafka.log.AbstractIndex.close(AbstractIndex.scala:178)
        at kafka.log.LogSegment.$anonfun$close$3(LogSegment.scala:478)
        at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:78)
        at kafka.utils.Logging.swallowWarn(Logging.scala:94)
        at kafka.utils.Logging.swallowWarn$(Logging.scala:93)
        at kafka.utils.CoreUtils$.swallowWarn(CoreUtils.scala:48)
        at kafka.utils.Logging.swallow(Logging.scala:96)
        at kafka.utils.Logging.swallow$(Logging.scala:96)
        at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:48)
        at kafka.log.LogSegment.close(LogSegment.scala:478)
        at kafka.log.Log.$anonfun$close$2(Log.scala:530)
        at kafka.log.Log.$anonfun$close$2$adapted(Log.scala:530)
        at scala.collection.Iterator.foreach(Iterator.scala:929)
        at scala.collection.Iterator.foreach$(Iterator.scala:929)
        at scala.collection.AbstractIterator.foreach(Iterator.scala:1417)
        at scala.collection.IterableLike.foreach(IterableLike.scala:71)
        at scala.collection.IterableLike.foreach$(IterableLike.scala:70)
        at scala.collection.AbstractIterable.foreach(Iterable.scala:54)
        at kafka.log.Log.close(Log.scala:530)
        at kafka.log.LogManager.$anonfun$shutdown$7(LogManager.scala:280)
        at kafka.utils.CoreUtils$$anon$1.run(CoreUtils.scala:57)
        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:748)
Caused by: java.lang.OutOfMemoryError: Map failed
        at sun.nio.ch.FileChannelImpl.map0(Native Method)
        at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:940)
        ... 33 more
[2017-09-22 09:52:28,703] INFO Shutdown complete. (kafka.log.LogManager)
[2017-09-22 09:52:28,725] INFO Terminate ZkClient event thread. (org.I0Itec.zkclient.ZkEventThread)
[2017-09-22 09:52:28,734] INFO Processed session termination for sessionid: 0x15ea850ecaf0000
(org.apache.zookeeper.server.PrepRequestProcessor)
[2017-09-22 09:52:28,739] INFO Session: 0x15ea850ecaf0000 closed (org.apache.zookeeper.ZooKeeper)
[2017-09-22 09:52:28,743] INFO Closed socket connection for client /127.0.0.1:53962 which
had sessionid 0x15ea850ecaf0000 (org.apache.zookeeper.server.NIOServerCnxn)
[2017-09-22 09:52:28,745] INFO EventThread shut down for session: 0x15ea850ecaf0000 (org.apache.zookeeper.ClientCnxn)
[2017-09-22 09:52:28,753] INFO [Kafka Server 0], shut down completed (kafka.server.KafkaServer)


> java.io.IOException: Map failed
> -------------------------------
>
>                 Key: KAFKA-5962
>                 URL: https://issues.apache.org/jira/browse/KAFKA-5962
>             Project: Kafka
>          Issue Type: Bug
>          Components: core
>    Affects Versions: 0.11.0.0
>         Environment: intalling kafka version: kafka_2.12-0.11.0.0
>            Reporter: Mehmet Soner
>            Priority: Critical
>
> Step to reproduce bugs:
> *1) Starting zookeeper by using below command.*
> zookeeper-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/zookeeper.properties
> *2) Starting kafka by using below command.*
> kafka-server-start.sh -daemon /usr/local/Apache/kafka_2.12-0.11.0.0/config/server0.properties
> *3) Writing data to topic*
> /prov/users/sas/bin$ $KAFKA_HOME/bin/kafka-console-producer.sh --broker-list 172.31.19.85:9092
--topic ssmtopup_topic
> >test1
> >test2
> >test3
> >test4
> >test5
> *server.log*
> [2017-09-22 09:51:24,467] INFO Updated PartitionLeaderEpoch. New: {epoch:7, offset:0},
Current: {epoch:-1, offset-1} for Partition: ssmtopup_topic-0. Cache now contains 0 entries.
(kafka.server.epoch.LeaderEpochFileCache)
> [2017-09-22 09:52:23,819] INFO [Group Metadata Manager on Broker 0]: Removed 0 expired
offsets in 0 milliseconds. (kafka.coordinator.group.GroupMetadataManager)
> *4) Exiting by pressing CTRL+C*
> *5) Shutting down kafka by using below command*
> *a) finding pid by uisin below command*
> jps -lm
> *b) graceful shutdown*
> kill -15 
> *You can find error below*
> *server.log *
> [2017-09-22 09:52:26,179] INFO [Kafka Server 0], shutting down (kafka.server.KafkaServer)
> [2017-09-22 09:52:26,219] INFO [Kafka Server 0], Starting controlled shutdown (kafka.server.KafkaServer)
> [2017-09-22 09:52:26,346] INFO [Kafka Server 0], Controlled shutdown succeeded (kafka.server.KafkaServer)
> [2017-09-22 09:52:26,356] INFO [Socket Server on Broker 0], Shutting down (kafka.network.SocketServer)
> [2017-09-22 09:52:26,384] INFO [Socket Server on Broker 0], Shutdown completed (kafka.network.SocketServer)
> [2017-09-22 09:52:26,386] INFO [Kafka Request Handler on Broker 0], shutting down (kafka.server.KafkaRequestHandlerPool)
> [2017-09-22 09:52:26,395] INFO [Kafka Request Handler on Broker 0], shut down completely
(kafka.server.KafkaRequestHandlerPool)
> [2017-09-22 09:52:26,411] INFO [ThrottledRequestReaper-Fetch]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,032] INFO [ThrottledRequestReaper-Fetch]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Fetch]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,034] INFO [ThrottledRequestReaper-Produce]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Produce]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,701] INFO [ThrottledRequestReaper-Request]: Shutting down (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Shutdown completed (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,985] INFO [ThrottledRequestReaper-Request]: Stopped (kafka.server.ClientQuotaManager$ThrottledRequestReaper)
> [2017-09-22 09:52:27,988] INFO [KafkaApi-0] Shutdown complete. (kafka.server.KafkaApis)
> [2017-09-22 09:52:27,991] INFO [ExpirationReaper-0-topic]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,082] INFO [ExpirationReaper-0-topic]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,088] INFO [ExpirationReaper-0-topic]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,096] INFO [Transaction Coordinator 0]: Shutting down. (kafka.coordinator.transaction.TransactionCoordinator)
> [2017-09-22 09:52:28,100] INFO [ProducerId Manager 0]: Shutdown complete: last producerId
assigned 7000 (kafka.coordinator.transaction.ProducerIdManager)
> [2017-09-22 09:52:28,102] INFO [Transaction State Manager 0]: Shutdown complete (kafka.coordinator.transaction.TransactionStateManager)
> [2017-09-22 09:52:28,102] INFO [Transaction Marker Channel Manager 0]: Shutting down
(kafka.coordinator.transaction.TransactionMarkerChannelManager)
> [2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Stopped (kafka.coordinator.transaction.TransactionMarkerChannelManager)
> [2017-09-22 09:52:28,104] INFO [Transaction Marker Channel Manager 0]: Shutdown completed
(kafka.coordinator.transaction.TransactionMarkerChannelManager)
> [2017-09-22 09:52:28,105] INFO [Transaction Coordinator 0]: Shutdown complete. (kafka.coordinator.transaction.TransactionCoordinator)
> [2017-09-22 09:52:28,107] INFO [GroupCoordinator 0]: Shutting down. (kafka.coordinator.group.GroupCoordinator)
> [2017-09-22 09:52:28,108] INFO [ExpirationReaper-0-Heartbeat]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,112] INFO [ExpirationReaper-0-Heartbeat]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,113] INFO [ExpirationReaper-0-Rebalance]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,114] INFO [GroupCoordinator 0]: Shutdown complete. (kafka.coordinator.group.GroupCoordinator)
> [2017-09-22 09:52:28,116] INFO [Replica Manager on Broker 0]: Shutting down (kafka.server.ReplicaManager)
> [2017-09-22 09:52:28,117] INFO [ReplicaFetcherManager on broker 0] shutting down (kafka.server.ReplicaFetcherManager)
> [2017-09-22 09:52:28,122] INFO [ReplicaFetcherManager on broker 0] shutdown completed
(kafka.server.ReplicaFetcherManager)
> [2017-09-22 09:52:28,122] INFO [ExpirationReaper-0-Fetch]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Fetch]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,289] INFO [ExpirationReaper-0-Produce]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,318] INFO [ExpirationReaper-0-Produce]: Shutdown completed (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,319] INFO [ExpirationReaper-0-DeleteRecords]: Shutting down (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Shutdown completed
(kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,379] INFO [ExpirationReaper-0-DeleteRecords]: Stopped (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
> [2017-09-22 09:52:28,400] INFO [Replica Manager on Broker 0]: Shut down completely (kafka.server.ReplicaManager)
> [2017-09-22 09:52:28,403] INFO Shutting down. (kafka.log.LogManager)
> [2017-09-22 09:52:28,644] WARN Map failed (kafka.utils.CoreUtils$)
> java.io.IOException: Map failed
>         at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:943)
>         at kafka.log.AbstractIndex.$anonfun$resize$1(AbstractIndex.scala:115)
>         at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
>         at kafka.log.AbstractIndex.resize(AbstractIndex.scala:105)
>         at kafka.log.AbstractIndex.$anonfun$trimToValidSize$1(AbstractIndex.scala:167)
>         at scala.runtime.java8.JFunction0$mcV$sp.apply(JFunction0$mcV$sp.java:12)
>         at kafka.utils.CoreUtils$.inLock(CoreUtils.scala:213)
>         at kafka.log.AbstractIndex.trimToValidSize(AbstractIndex.scala:167)
>         at kafka.log.AbstractIndex.close(AbstractIndex.scala:178)
>         at kafka.log.LogSegment.$anonfun$close$3(LogSegment.scala:478)
>         at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:78)
>         at kafka.utils.Logging.swallowWarn(Logging.scala:94)
>         at kafka.utils.Logging.swallowWarn$(Logging.scala:93)
>         at kafka.utils.CoreUtils$.swallowWarn(CoreUtils.scala:48)
>         at kafka.utils.Logging.swallow(Logging.scala:96)
>         at kafka.utils.Logging.swallow$(Logging.scala:96)
>         at kafka.utils.CoreUtils$.swallow(CoreUtils.scala:48)
>         at kafka.log.LogSegment.close(LogSegment.scala:478)
>         at kafka.log.Log.$anonfun$close$2(Log.scala:530)
>         at kafka.log.Log.$anonfun$close$2$adapted(Log.scala:530)
>         at scala.collection.Iterator.foreach(Iterator.scala:929)
>         at scala.collection.Iterator.foreach$(Iterator.scala:929)
>         at scala.collection.AbstractIterator.foreach(Iterator.scala:1417)
>         at scala.collection.IterableLike.foreach(IterableLike.scala:71)
>         at scala.collection.IterableLike.foreach$(IterableLike.scala:70)
>         at scala.collection.AbstractIterable.foreach(Iterable.scala:54)
>         at kafka.log.Log.close(Log.scala:530)
>         at kafka.log.LogManager.$anonfun$shutdown$7(LogManager.scala:280)
>         at kafka.utils.CoreUtils$$anon$1.run(CoreUtils.scala:57)
>         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:748)
> Caused by: java.lang.OutOfMemoryError: Map failed
>         at sun.nio.ch.FileChannelImpl.map0(Native Method)
>         at sun.nio.ch.FileChannelImpl.map(FileChannelImpl.java:940)
>         ... 33 more
> [2017-09-22 09:52:28,703] INFO Shutdown complete. (kafka.log.LogManager)
> [2017-09-22 09:52:28,725] INFO Terminate ZkClient event thread. (org.I0Itec.zkclient.ZkEventThread)
> [2017-09-22 09:52:28,734] INFO Processed session termination for sessionid: 0x15ea850ecaf0000
(org.apache.zookeeper.server.PrepRequestProcessor)
> [2017-09-22 09:52:28,739] INFO Session: 0x15ea850ecaf0000 closed (org.apache.zookeeper.ZooKeeper)
> [2017-09-22 09:52:28,743] INFO Closed socket connection for client /127.0.0.1:53962 which
had sessionid 0x15ea850ecaf0000 (org.apache.zookeeper.server.NIOServerCnxn)
> [2017-09-22 09:52:28,745] INFO EventThread shut down for session: 0x15ea850ecaf0000 (org.apache.zookeeper.ClientCnxn)
> [2017-09-22 09:52:28,753] INFO [Kafka Server 0], shut down completed (kafka.server.KafkaServer)



--
This message was sent by Atlassian JIRA
(v6.4.14#64029)

Mime
View raw message